builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1472199864.84 results: success (0) buildid: 20160825223021 builduid: 20a68942304e46068a7fa76cdb580889 revision: e78454e242072bbcada626874f2900bbe1c3e647 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.843112) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.843640) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.843990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.859117) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.859479) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 01:24:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-08-26 01:24:25 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.997836) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:24.998312) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020365 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.048348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.048736) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e78454e242072bbcada626874f2900bbe1c3e647 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e78454e242072bbcada626874f2900bbe1c3e647 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 01:24:25,384 truncating revision to first 12 chars 2016-08-26 01:24:25,384 Setting DEBUG logging. 2016-08-26 01:24:25,384 attempt 1/10 2016-08-26 01:24:25,384 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/e78454e24207?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 01:24:25,976 unpacking tar archive at: mozilla-inbound-e78454e24207/testing/mozharness/ program finished with exit code 0 elapsedTime=0.880517 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.943664) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.944022) ========= script_repo_revision: e78454e242072bbcada626874f2900bbe1c3e647 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.944512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.944820) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 01:24:25.957968) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-26 01:24:25.958301) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:24:26 INFO - MultiFileLogger online at 20160826 01:24:26 in /builds/slave/test 01:24:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 01:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:24:26 INFO - {'append_to_log': False, 01:24:26 INFO - 'base_work_dir': '/builds/slave/test', 01:24:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 01:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:24:26 INFO - 'branch': 'Mozilla-Inbound', 01:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 01:24:26 INFO - 'config_files': ('talos/linux_config.py',), 01:24:26 INFO - 'default_actions': ('clobber', 01:24:26 INFO - 'read-buildbot-config', 01:24:26 INFO - 'download-and-extract', 01:24:26 INFO - 'populate-webroot', 01:24:26 INFO - 'create-virtualenv', 01:24:26 INFO - 'install', 01:24:26 INFO - 'run-tests'), 01:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:24:26 INFO - 'download_minidump_stackwalk': True, 01:24:26 INFO - 'download_symbols': 'ondemand', 01:24:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:24:26 INFO - 'tooltool.py': '/tools/tooltool.py', 01:24:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:24:26 INFO - '/tools/misc-python/virtualenv.py')}, 01:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:24:26 INFO - 'installer_path': 'installer.exe', 01:24:26 INFO - 'log_level': 'info', 01:24:26 INFO - 'log_name': 'talos', 01:24:26 INFO - 'log_to_console': True, 01:24:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:24:26 INFO - 'opt_config_files': (), 01:24:26 INFO - 'pip_index': False, 01:24:26 INFO - 'sps_profile': False, 01:24:26 INFO - 'sps_profile_interval': 0, 01:24:26 INFO - 'suite': 'dromaeojs', 01:24:26 INFO - 'system_bits': '32', 01:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:24:26 INFO - 'title': 'talos-linux64-ix-036', 01:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:24:26 INFO - 'use_talos_json': True, 01:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:24:26 INFO - 'work_dir': 'build'} 01:24:26 INFO - [mozharness: 2016-08-26 08:24:26.394789Z] Running clobber step. 01:24:26 INFO - Running pre-action listener: _resource_record_pre_action 01:24:26 INFO - Running main action method: clobber 01:24:26 INFO - rmtree: /builds/slave/test/build 01:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:24:26 INFO - Running post-action listener: _resource_record_post_action 01:24:26 INFO - [mozharness: 2016-08-26 08:24:26.856150Z] Finished clobber step (success) 01:24:26 INFO - [mozharness: 2016-08-26 08:24:26.856252Z] Running read-buildbot-config step. 01:24:26 INFO - Running pre-action listener: _resource_record_pre_action 01:24:26 INFO - Running main action method: read_buildbot_config 01:24:26 INFO - Using buildbot properties: 01:24:26 INFO - { 01:24:26 INFO - "project": "", 01:24:26 INFO - "product": "firefox", 01:24:26 INFO - "script_repo_revision": "production", 01:24:26 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 01:24:26 INFO - "repository": "", 01:24:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 01:24:26 INFO - "buildid": "20160825223021", 01:24:26 INFO - "pgo_build": "True", 01:24:26 INFO - "basedir": "/builds/slave/test", 01:24:26 INFO - "buildnumber": 1452, 01:24:26 INFO - "slavename": "talos-linux64-ix-036", 01:24:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:24:26 INFO - "platform": "ubuntu64_hw", 01:24:26 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 01:24:26 INFO - "branch": "mozilla-inbound", 01:24:26 INFO - "repo_path": "integration/mozilla-inbound", 01:24:26 INFO - "revision": "e78454e242072bbcada626874f2900bbe1c3e647", 01:24:26 INFO - "stage_platform": "linux64-pgo", 01:24:26 INFO - "builduid": "20a68942304e46068a7fa76cdb580889", 01:24:26 INFO - "slavebuilddir": "test" 01:24:26 INFO - } 01:24:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 01:24:26 INFO - Running post-action listener: _resource_record_post_action 01:24:26 INFO - [mozharness: 2016-08-26 08:24:26.858708Z] Finished read-buildbot-config step (success) 01:24:26 INFO - [mozharness: 2016-08-26 08:24:26.858797Z] Running download-and-extract step. 01:24:26 INFO - Running pre-action listener: _resource_record_pre_action 01:24:26 INFO - Running main action method: download_and_extract 01:24:26 INFO - mkdir: /builds/slave/test/build/tests 01:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:26 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:24:26 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:24:27 INFO - Downloaded 1361 bytes. 01:24:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 01:24:27 INFO - Using the following test package requirements: 01:24:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:24:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:24:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:24:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'jsshell-linux-x86_64.zip'], 01:24:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:24:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 01:24:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:24:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:24:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:24:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 01:24:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:24:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:27 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:24:27 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:24:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:24:28 INFO - Downloaded 21871778 bytes. 01:24:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:24:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:32 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:24:32 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 01:24:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:24:33 INFO - Downloaded 21871778 bytes. 01:24:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 01:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:38 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:24:38 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 01:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:24:39 INFO - Downloaded 14413981 bytes. 01:24:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 01:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:39 INFO - trying https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:24:39 INFO - Downloading https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:24:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:24:40 INFO - Downloaded 59710219 bytes. 01:24:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:24:40 INFO - mkdir: /builds/slave/test/properties 01:24:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:24:40 INFO - Writing to file /builds/slave/test/properties/build_url 01:24:40 INFO - Contents: 01:24:40 INFO - build_url:https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 01:24:41 INFO - Running post-action listener: _resource_record_post_action 01:24:41 INFO - Running post-action listener: set_extra_try_arguments 01:24:41 INFO - [mozharness: 2016-08-26 08:24:41.284136Z] Finished download-and-extract step (success) 01:24:41 INFO - [mozharness: 2016-08-26 08:24:41.284363Z] Running populate-webroot step. 01:24:41 INFO - Running pre-action listener: _resource_record_pre_action 01:24:41 INFO - Running main action method: populate_webroot 01:24:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:24:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:24:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:24:41 INFO - '--tppagecycles', 01:24:41 INFO - '7'], 01:24:41 INFO - 'tests': ['tsvgm']}}, 01:24:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:24:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:24:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:24:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:24:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:24:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:24:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['damp', 'tps']}, 01:24:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'tests': ['damp', 'tps']}, 01:24:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['dromaeo_dom']}, 01:24:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:24:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['basic_compositor_video']}, 01:24:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:24:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'other-e10s': {'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['a11yr', 01:24:41 INFO - 'ts_paint', 01:24:41 INFO - 'tpaint', 01:24:41 INFO - 'sessionrestore', 01:24:41 INFO - 'sessionrestore_no_auto_restore', 01:24:41 INFO - 'tabpaint']}, 01:24:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['tsvgx', 01:24:41 INFO - 'tsvgr_opacity', 01:24:41 INFO - 'tart', 01:24:41 INFO - 'tscrollx', 01:24:41 INFO - 'cart']}, 01:24:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:24:41 INFO - 'tsvgr_opacity', 01:24:41 INFO - 'tart', 01:24:41 INFO - 'tscrollx', 01:24:41 INFO - 'cart']}, 01:24:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'talos_options': ['--disable-e10s'], 01:24:41 INFO - 'tests': ['tp5o']}, 01:24:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'tests': ['tp5o']}, 01:24:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'talos_options': ['--disable-e10s', 01:24:41 INFO - '--xperf_path', 01:24:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:24:41 INFO - 'tests': ['tp5n']}, 01:24:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:24:41 INFO - 'talos_options': ['--xperf_path', 01:24:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:24:41 INFO - 'tests': ['tp5n']}}, 01:24:41 INFO - 'talos.zip': {'path': '', 01:24:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:24:41 INFO - Running post-action listener: _resource_record_post_action 01:24:41 INFO - [mozharness: 2016-08-26 08:24:41.306233Z] Finished populate-webroot step (success) 01:24:41 INFO - [mozharness: 2016-08-26 08:24:41.306322Z] Running create-virtualenv step. 01:24:41 INFO - Running pre-action listener: _resource_record_pre_action 01:24:41 INFO - Running main action method: create_virtualenv 01:24:41 INFO - Creating virtualenv /builds/slave/test/build/venv 01:24:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:24:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:24:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:24:41 INFO - Using real prefix '/usr' 01:24:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:24:41 INFO - Installing distribute.............................................................................................................................................................................................done. 01:24:43 INFO - Installing pip.................done. 01:24:43 INFO - Return code: 0 01:24:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:24:43 INFO - Using env: {'DISPLAY': ':0', 01:24:43 INFO - 'HOME': '/home/cltbld', 01:24:43 INFO - 'LANG': 'en_US.UTF-8', 01:24:43 INFO - 'LANGUAGE': 'en_US:en', 01:24:43 INFO - 'LOGNAME': 'cltbld', 01:24:43 INFO - 'MAIL': '/var/mail/cltbld', 01:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:43 INFO - 'MOZ_NO_REMOTE': '1', 01:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:43 INFO - 'NO_EM_RESTART': '1', 01:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:43 INFO - 'PWD': '/builds/slave/test', 01:24:43 INFO - 'SHELL': '/bin/bash', 01:24:43 INFO - 'SHLVL': '1', 01:24:43 INFO - 'TERM': 'linux', 01:24:43 INFO - 'TMOUT': '86400', 01:24:43 INFO - 'USER': 'cltbld', 01:24:43 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:43 INFO - '_': '/tools/buildbot/bin/python'} 01:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:24:44 INFO - Cleaning up... 01:24:44 INFO - Return code: 0 01:24:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:24:44 INFO - Using env: {'DISPLAY': ':0', 01:24:44 INFO - 'HOME': '/home/cltbld', 01:24:44 INFO - 'LANG': 'en_US.UTF-8', 01:24:44 INFO - 'LANGUAGE': 'en_US:en', 01:24:44 INFO - 'LOGNAME': 'cltbld', 01:24:44 INFO - 'MAIL': '/var/mail/cltbld', 01:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:44 INFO - 'MOZ_NO_REMOTE': '1', 01:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:44 INFO - 'NO_EM_RESTART': '1', 01:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:44 INFO - 'PWD': '/builds/slave/test', 01:24:44 INFO - 'SHELL': '/bin/bash', 01:24:44 INFO - 'SHLVL': '1', 01:24:44 INFO - 'TERM': 'linux', 01:24:44 INFO - 'TMOUT': '86400', 01:24:44 INFO - 'USER': 'cltbld', 01:24:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:44 INFO - '_': '/tools/buildbot/bin/python'} 01:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:44 INFO - Downloading/unpacking psutil>=3.1.1 01:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:24:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:24:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:24:46 INFO - Installing collected packages: psutil 01:24:46 INFO - Running setup.py install for psutil 01:24:46 INFO - building 'psutil._psutil_linux' extension 01:24:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:24:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:24:46 INFO - building 'psutil._psutil_posix' extension 01:24:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:24:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:24:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:24:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:24:46 INFO - Successfully installed psutil 01:24:46 INFO - Cleaning up... 01:24:46 INFO - Return code: 0 01:24:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:24:46 INFO - Using env: {'DISPLAY': ':0', 01:24:46 INFO - 'HOME': '/home/cltbld', 01:24:46 INFO - 'LANG': 'en_US.UTF-8', 01:24:46 INFO - 'LANGUAGE': 'en_US:en', 01:24:46 INFO - 'LOGNAME': 'cltbld', 01:24:46 INFO - 'MAIL': '/var/mail/cltbld', 01:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:46 INFO - 'MOZ_NO_REMOTE': '1', 01:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:46 INFO - 'NO_EM_RESTART': '1', 01:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:46 INFO - 'PWD': '/builds/slave/test', 01:24:46 INFO - 'SHELL': '/bin/bash', 01:24:46 INFO - 'SHLVL': '1', 01:24:46 INFO - 'TERM': 'linux', 01:24:46 INFO - 'TMOUT': '86400', 01:24:46 INFO - 'USER': 'cltbld', 01:24:46 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:46 INFO - '_': '/tools/buildbot/bin/python'} 01:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:24:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:24:48 INFO - Installing collected packages: mozsystemmonitor 01:24:48 INFO - Running setup.py install for mozsystemmonitor 01:24:49 INFO - Successfully installed mozsystemmonitor 01:24:49 INFO - Cleaning up... 01:24:49 INFO - Return code: 0 01:24:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:24:49 INFO - Using env: {'DISPLAY': ':0', 01:24:49 INFO - 'HOME': '/home/cltbld', 01:24:49 INFO - 'LANG': 'en_US.UTF-8', 01:24:49 INFO - 'LANGUAGE': 'en_US:en', 01:24:49 INFO - 'LOGNAME': 'cltbld', 01:24:49 INFO - 'MAIL': '/var/mail/cltbld', 01:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:49 INFO - 'MOZ_NO_REMOTE': '1', 01:24:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:49 INFO - 'NO_EM_RESTART': '1', 01:24:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:49 INFO - 'PWD': '/builds/slave/test', 01:24:49 INFO - 'SHELL': '/bin/bash', 01:24:49 INFO - 'SHLVL': '1', 01:24:49 INFO - 'TERM': 'linux', 01:24:49 INFO - 'TMOUT': '86400', 01:24:49 INFO - 'USER': 'cltbld', 01:24:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:49 INFO - '_': '/tools/buildbot/bin/python'} 01:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:49 INFO - Downloading/unpacking jsonschema==2.5.1 01:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:24:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:51 INFO - Downloading functools32-3.2.3-2.tar.gz 01:24:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:24:51 INFO - warning: no files found matching '*.txt' 01:24:51 INFO - no previously-included directories found matching 'build' 01:24:51 INFO - no previously-included directories found matching 'dist' 01:24:51 INFO - no previously-included directories found matching '.git*' 01:24:51 INFO - Installing collected packages: jsonschema, functools32 01:24:51 INFO - Running setup.py install for functools32 01:24:51 INFO - warning: no files found matching '*.txt' 01:24:51 INFO - no previously-included directories found matching 'build' 01:24:51 INFO - no previously-included directories found matching 'dist' 01:24:51 INFO - no previously-included directories found matching '.git*' 01:24:51 INFO - Successfully installed jsonschema functools32 01:24:51 INFO - Cleaning up... 01:24:51 INFO - Return code: 0 01:24:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:24:51 INFO - Using env: {'DISPLAY': ':0', 01:24:51 INFO - 'HOME': '/home/cltbld', 01:24:51 INFO - 'LANG': 'en_US.UTF-8', 01:24:51 INFO - 'LANGUAGE': 'en_US:en', 01:24:51 INFO - 'LOGNAME': 'cltbld', 01:24:51 INFO - 'MAIL': '/var/mail/cltbld', 01:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:51 INFO - 'MOZ_NO_REMOTE': '1', 01:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:51 INFO - 'NO_EM_RESTART': '1', 01:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:51 INFO - 'PWD': '/builds/slave/test', 01:24:51 INFO - 'SHELL': '/bin/bash', 01:24:51 INFO - 'SHLVL': '1', 01:24:51 INFO - 'TERM': 'linux', 01:24:51 INFO - 'TMOUT': '86400', 01:24:51 INFO - 'USER': 'cltbld', 01:24:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:51 INFO - '_': '/tools/buildbot/bin/python'} 01:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:24:51 INFO - Cleaning up... 01:24:51 INFO - Return code: 0 01:24:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:24:51 INFO - Using env: {'DISPLAY': ':0', 01:24:51 INFO - 'HOME': '/home/cltbld', 01:24:51 INFO - 'LANG': 'en_US.UTF-8', 01:24:51 INFO - 'LANGUAGE': 'en_US:en', 01:24:51 INFO - 'LOGNAME': 'cltbld', 01:24:51 INFO - 'MAIL': '/var/mail/cltbld', 01:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:51 INFO - 'MOZ_NO_REMOTE': '1', 01:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:51 INFO - 'NO_EM_RESTART': '1', 01:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:51 INFO - 'PWD': '/builds/slave/test', 01:24:51 INFO - 'SHELL': '/bin/bash', 01:24:51 INFO - 'SHLVL': '1', 01:24:51 INFO - 'TERM': 'linux', 01:24:51 INFO - 'TMOUT': '86400', 01:24:51 INFO - 'USER': 'cltbld', 01:24:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:51 INFO - '_': '/tools/buildbot/bin/python'} 01:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:52 INFO - Downloading/unpacking blobuploader==1.2.4 01:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:53 INFO - Downloading blobuploader-1.2.4.tar.gz 01:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:24:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:24:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:24:54 INFO - Downloading docopt-0.6.1.tar.gz 01:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:24:54 INFO - Installing collected packages: blobuploader, requests, docopt 01:24:54 INFO - Running setup.py install for blobuploader 01:24:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:24:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:24:54 INFO - Running setup.py install for requests 01:24:55 INFO - Running setup.py install for docopt 01:24:55 INFO - Successfully installed blobuploader requests docopt 01:24:55 INFO - Cleaning up... 01:24:55 INFO - Return code: 0 01:24:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:24:55 INFO - Using env: {'DISPLAY': ':0', 01:24:55 INFO - 'HOME': '/home/cltbld', 01:24:55 INFO - 'LANG': 'en_US.UTF-8', 01:24:55 INFO - 'LANGUAGE': 'en_US:en', 01:24:55 INFO - 'LOGNAME': 'cltbld', 01:24:55 INFO - 'MAIL': '/var/mail/cltbld', 01:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:55 INFO - 'MOZ_NO_REMOTE': '1', 01:24:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:55 INFO - 'NO_EM_RESTART': '1', 01:24:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:55 INFO - 'PWD': '/builds/slave/test', 01:24:55 INFO - 'SHELL': '/bin/bash', 01:24:55 INFO - 'SHLVL': '1', 01:24:55 INFO - 'TERM': 'linux', 01:24:55 INFO - 'TMOUT': '86400', 01:24:55 INFO - 'USER': 'cltbld', 01:24:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:55 INFO - '_': '/tools/buildbot/bin/python'} 01:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:24:55 INFO - Running setup.py (path:/tmp/pip-kAdO3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:24:55 INFO - Running setup.py (path:/tmp/pip-7hVjWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:24:55 INFO - Running setup.py (path:/tmp/pip-sliYv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:24:55 INFO - Running setup.py (path:/tmp/pip-RRIro8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:24:55 INFO - Running setup.py (path:/tmp/pip-XxP7F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:24:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:24:55 INFO - Running setup.py (path:/tmp/pip-IxOCyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:24:56 INFO - Running setup.py (path:/tmp/pip-_x269v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:24:56 INFO - Running setup.py (path:/tmp/pip-DI6Akd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:24:56 INFO - Running setup.py (path:/tmp/pip-614IDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:24:56 INFO - Running setup.py (path:/tmp/pip-2W6bTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:24:56 INFO - Running setup.py (path:/tmp/pip-mmmsb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:24:56 INFO - Running setup.py (path:/tmp/pip-i_wPvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:24:56 INFO - Running setup.py (path:/tmp/pip-QucRGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:24:56 INFO - Running setup.py (path:/tmp/pip-hoYmKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:56 INFO - Running setup.py (path:/tmp/pip-RIVl6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:24:56 INFO - Running setup.py (path:/tmp/pip-FYCM8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:24:56 INFO - Running setup.py (path:/tmp/pip-VuYAdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:24:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:24:56 INFO - Running setup.py install for manifestparser 01:24:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:24:56 INFO - Running setup.py install for mozcrash 01:24:57 INFO - Running setup.py install for mozdebug 01:24:57 INFO - Running setup.py install for mozdevice 01:24:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for mozfile 01:24:57 INFO - Running setup.py install for mozhttpd 01:24:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for mozinfo 01:24:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for mozInstall 01:24:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for mozleak 01:24:57 INFO - Running setup.py install for mozlog 01:24:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for moznetwork 01:24:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:24:57 INFO - Running setup.py install for mozprocess 01:24:58 INFO - Running setup.py install for mozprofile 01:24:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:24:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:24:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:24:58 INFO - Running setup.py install for mozrunner 01:24:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:24:58 INFO - Running setup.py install for mozscreenshot 01:24:58 INFO - Running setup.py install for moztest 01:24:58 INFO - Running setup.py install for mozversion 01:24:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:24:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:24:58 INFO - Cleaning up... 01:24:58 INFO - Return code: 0 01:24:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:24:58 INFO - Using env: {'DISPLAY': ':0', 01:24:58 INFO - 'HOME': '/home/cltbld', 01:24:58 INFO - 'LANG': 'en_US.UTF-8', 01:24:58 INFO - 'LANGUAGE': 'en_US:en', 01:24:58 INFO - 'LOGNAME': 'cltbld', 01:24:58 INFO - 'MAIL': '/var/mail/cltbld', 01:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:24:58 INFO - 'MOZ_NO_REMOTE': '1', 01:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:24:58 INFO - 'NO_EM_RESTART': '1', 01:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:24:58 INFO - 'PWD': '/builds/slave/test', 01:24:58 INFO - 'SHELL': '/bin/bash', 01:24:58 INFO - 'SHLVL': '1', 01:24:58 INFO - 'TERM': 'linux', 01:24:58 INFO - 'TMOUT': '86400', 01:24:58 INFO - 'USER': 'cltbld', 01:24:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:24:58 INFO - '_': '/tools/buildbot/bin/python'} 01:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:24:58 INFO - Running setup.py (path:/tmp/pip-vbkfEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:24:58 INFO - Running setup.py (path:/tmp/pip-VXj9JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:24:58 INFO - Running setup.py (path:/tmp/pip-kkl9tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:24:59 INFO - Running setup.py (path:/tmp/pip-9kIwky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:24:59 INFO - Running setup.py (path:/tmp/pip-U6aqnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:24:59 INFO - Running setup.py (path:/tmp/pip-QDkGqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:24:59 INFO - Running setup.py (path:/tmp/pip-mqaSAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:24:59 INFO - Running setup.py (path:/tmp/pip-TYHRe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:24:59 INFO - Running setup.py (path:/tmp/pip-rlgknH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:24:59 INFO - Running setup.py (path:/tmp/pip-bQ_1nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:24:59 INFO - Running setup.py (path:/tmp/pip-naUAFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:24:59 INFO - Running setup.py (path:/tmp/pip-iWjzd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:24:59 INFO - Running setup.py (path:/tmp/pip-ArSQpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:24:59 INFO - Running setup.py (path:/tmp/pip-Vb8fBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:59 INFO - Running setup.py (path:/tmp/pip-DjxwQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:24:59 INFO - Running setup.py (path:/tmp/pip-ARxcnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:24:59 INFO - Running setup.py (path:/tmp/pip-8XbBFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:25:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:25:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:25:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:25:01 INFO - Downloading blessings-1.6.tar.gz 01:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:25:02 INFO - Installing collected packages: blessings 01:25:02 INFO - Running setup.py install for blessings 01:25:02 INFO - Successfully installed blessings 01:25:02 INFO - Cleaning up... 01:25:02 INFO - Return code: 0 01:25:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:25:02 INFO - Reading from file tmpfile_stdout 01:25:02 INFO - Current package versions: 01:25:02 INFO - argparse == 1.2.1 01:25:02 INFO - blessings == 1.6 01:25:02 INFO - blobuploader == 1.2.4 01:25:02 INFO - docopt == 0.6.1 01:25:02 INFO - functools32 == 3.2.3-2 01:25:02 INFO - jsonschema == 2.5.1 01:25:02 INFO - manifestparser == 1.1 01:25:02 INFO - mozInstall == 1.12 01:25:02 INFO - mozcrash == 0.17 01:25:02 INFO - mozdebug == 0.1 01:25:02 INFO - mozdevice == 0.48 01:25:02 INFO - mozfile == 1.2 01:25:02 INFO - mozhttpd == 0.7 01:25:02 INFO - mozinfo == 0.9 01:25:02 INFO - mozleak == 0.1 01:25:02 INFO - mozlog == 3.3 01:25:02 INFO - moznetwork == 0.27 01:25:02 INFO - mozprocess == 0.23 01:25:02 INFO - mozprofile == 0.28 01:25:02 INFO - mozrunner == 6.12 01:25:02 INFO - mozscreenshot == 0.1 01:25:02 INFO - mozsystemmonitor == 0.3 01:25:02 INFO - moztest == 0.7 01:25:02 INFO - mozversion == 1.4 01:25:02 INFO - psutil == 3.1.1 01:25:02 INFO - requests == 1.2.3 01:25:02 INFO - wsgiref == 0.1.2 01:25:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:25:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:25:02 INFO - Using env: {'DISPLAY': ':0', 01:25:02 INFO - 'HOME': '/home/cltbld', 01:25:02 INFO - 'LANG': 'en_US.UTF-8', 01:25:02 INFO - 'LANGUAGE': 'en_US:en', 01:25:02 INFO - 'LOGNAME': 'cltbld', 01:25:02 INFO - 'MAIL': '/var/mail/cltbld', 01:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:25:02 INFO - 'MOZ_NO_REMOTE': '1', 01:25:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:25:02 INFO - 'NO_EM_RESTART': '1', 01:25:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:25:02 INFO - 'PWD': '/builds/slave/test', 01:25:02 INFO - 'SHELL': '/bin/bash', 01:25:02 INFO - 'SHLVL': '1', 01:25:02 INFO - 'TERM': 'linux', 01:25:02 INFO - 'TMOUT': '86400', 01:25:02 INFO - 'USER': 'cltbld', 01:25:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:25:02 INFO - '_': '/tools/buildbot/bin/python'} 01:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:25:02 INFO - Cleaning up... 01:25:02 INFO - Return code: 0 01:25:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:25:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f61c3acdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d1b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:25:02 INFO - Using env: {'DISPLAY': ':0', 01:25:02 INFO - 'HOME': '/home/cltbld', 01:25:02 INFO - 'LANG': 'en_US.UTF-8', 01:25:02 INFO - 'LANGUAGE': 'en_US:en', 01:25:02 INFO - 'LOGNAME': 'cltbld', 01:25:02 INFO - 'MAIL': '/var/mail/cltbld', 01:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:25:02 INFO - 'MOZ_NO_REMOTE': '1', 01:25:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:25:02 INFO - 'NO_EM_RESTART': '1', 01:25:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:25:02 INFO - 'PWD': '/builds/slave/test', 01:25:02 INFO - 'SHELL': '/bin/bash', 01:25:02 INFO - 'SHLVL': '1', 01:25:02 INFO - 'TERM': 'linux', 01:25:02 INFO - 'TMOUT': '86400', 01:25:02 INFO - 'USER': 'cltbld', 01:25:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:25:02 INFO - '_': '/tools/buildbot/bin/python'} 01:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:25:02 INFO - Cleaning up... 01:25:02 INFO - Return code: 0 01:25:02 INFO - Running post-action listener: _resource_record_post_action 01:25:02 INFO - Running post-action listener: _start_resource_monitoring 01:25:02 INFO - Starting resource monitoring. 01:25:02 INFO - [mozharness: 2016-08-26 08:25:02.880957Z] Finished create-virtualenv step (success) 01:25:02 INFO - [mozharness: 2016-08-26 08:25:02.881425Z] Running install step. 01:25:02 INFO - Running pre-action listener: _resource_record_pre_action 01:25:02 INFO - Running main action method: install 01:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:25:03 INFO - Reading from file tmpfile_stdout 01:25:03 INFO - Detecting whether we're running mozinstall >=1.0... 01:25:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:25:03 INFO - Reading from file tmpfile_stdout 01:25:03 INFO - Output received: 01:25:03 INFO - Usage: mozinstall [options] installer 01:25:03 INFO - Options: 01:25:03 INFO - -h, --help show this help message and exit 01:25:03 INFO - -d DEST, --destination=DEST 01:25:03 INFO - Directory to install application into. [default: 01:25:03 INFO - "/builds/slave/test"] 01:25:03 INFO - --app=APP Application being installed. [default: firefox] 01:25:03 INFO - mkdir: /builds/slave/test/build/application 01:25:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:25:20 INFO - Reading from file tmpfile_stdout 01:25:20 INFO - Output received: 01:25:20 INFO - /builds/slave/test/build/application/firefox/firefox 01:25:20 INFO - Running post-action listener: _resource_record_post_action 01:25:20 INFO - [mozharness: 2016-08-26 08:25:20.185591Z] Finished install step (success) 01:25:20 INFO - [mozharness: 2016-08-26 08:25:20.185852Z] Running run-tests step. 01:25:20 INFO - Running pre-action listener: _resource_record_pre_action 01:25:20 INFO - Running main action method: run_tests 01:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:25:20 INFO - Python 2.7.3 01:25:20 INFO - Return code: 0 01:25:20 INFO - grabbing minidump binary from tooltool 01:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de4090>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de79b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de7e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:25:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:25:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:25:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:25:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:25:20 INFO - Return code: 0 01:25:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:25:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:25:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:25:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:25:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:25:20 INFO - Using env: {'DISPLAY': ':0', 01:25:20 INFO - 'HOME': '/home/cltbld', 01:25:20 INFO - 'LANG': 'en_US.UTF-8', 01:25:20 INFO - 'LANGUAGE': 'en_US:en', 01:25:20 INFO - 'LOGNAME': 'cltbld', 01:25:20 INFO - 'MAIL': '/var/mail/cltbld', 01:25:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:25:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:25:20 INFO - 'MOZ_NO_REMOTE': '1', 01:25:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:25:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:25:20 INFO - 'NO_EM_RESTART': '1', 01:25:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:25:20 INFO - 'PWD': '/builds/slave/test', 01:25:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:25:20 INFO - 'SHELL': '/bin/bash', 01:25:20 INFO - 'SHLVL': '1', 01:25:20 INFO - 'TERM': 'linux', 01:25:20 INFO - 'TMOUT': '86400', 01:25:20 INFO - 'USER': 'cltbld', 01:25:20 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618', 01:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:25:20 INFO - '_': '/tools/buildbot/bin/python'} 01:25:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:25:20 INFO - mozversion application_buildid: 20160825223021 01:25:20 INFO - mozversion application_changeset: e78454e242072bbcada626874f2900bbe1c3e647 01:25:20 INFO - mozversion application_display_name: Nightly 01:25:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:25:20 INFO - mozversion application_name: Firefox 01:25:20 INFO - mozversion application_remotingname: firefox 01:25:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:25:20 INFO - mozversion application_vendor: Mozilla 01:25:20 INFO - mozversion application_version: 51.0a1 01:25:20 INFO - mozversion platform_buildid: 20160825223021 01:25:20 INFO - mozversion platform_changeset: e78454e242072bbcada626874f2900bbe1c3e647 01:25:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:25:20 INFO - mozversion platform_version: 51.0a1 01:25:20 INFO - using testdate: 1472199920 01:25:20 INFO - actual date: 1472199920 01:25:20 INFO - starting webserver on 'localhost:47451' 01:25:20 INFO - SUITE-START | Running 2 tests 01:25:20 INFO - TEST-START | dromaeo_css 01:25:20 INFO - operating with platform_type : linux_ 01:25:20 INFO - Initialising browser for dromaeo_css test... 01:25:20 INFO - TEST-INFO | started process 14342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpevR4Fy/profile http://localhost:47451/getInfo.html) 01:25:24 INFO - PROCESS | 14342 | __metrics Screen width/height:1600/1200 01:25:24 INFO - PROCESS | 14342 | colorDepth:24 01:25:24 INFO - PROCESS | 14342 | Browser inner width/height: 1024/697 01:25:24 INFO - PROCESS | 14342 | __metrics 01:25:25 INFO - PROCESS | 14342 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:25:25 INFO - PROCESS | 14342 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:25:25 INFO - PROCESS | 14342 | console.error: PushService: 01:25:25 INFO - PROCESS | 14342 | stateChangeProcessEnqueue: Error transitioning state 01:25:25 INFO - PROCESS | 14342 | UnknownError 01:25:26 INFO - TEST-INFO | 14342: exit 0 01:25:26 INFO - Browser initialized. 01:25:26 INFO - Running cycle 1/1 for dromaeo_css test... 01:25:26 INFO - TEST-INFO | started process 14426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpevR4Fy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:25:38 INFO - PROCESS | 14426 | RSS: Main: 183250944 01:25:38 INFO - PROCESS | 14426 | 01:26:30 INFO - PROCESS | 14426 | 1472199990054 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:28:39 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47451/tests/dromaeo/cssquery-ext.html) 01:28:40 INFO - PROCESS | 14426 | RSS: Main: 273559552 01:28:40 INFO - PROCESS | 14426 | 01:31:43 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-ext.html (next: http://localhost:47451/tests/dromaeo/cssquery-jquery.html) 01:31:43 INFO - PROCESS | 14426 | RSS: Main: 273633280 01:31:43 INFO - PROCESS | 14426 | 01:34:45 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47451/tests/dromaeo/cssquery-mootools.html) 01:34:46 INFO - PROCESS | 14426 | RSS: Main: 274030592 01:34:46 INFO - PROCESS | 14426 | 01:37:48 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47451/tests/dromaeo/cssquery-prototype.html) 01:37:48 INFO - PROCESS | 14426 | RSS: Main: 256913408 01:37:48 INFO - PROCESS | 14426 | 01:40:51 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47451/tests/dromaeo/cssquery-yui.html) 01:40:52 INFO - PROCESS | 14426 | RSS: Main: 219754496 01:40:52 INFO - PROCESS | 14426 | 01:43:54 INFO - PROCESS | 14426 | Cycle 1(1): loaded http://localhost:47451/tests/dromaeo/cssquery-yui.html (next: http://localhost:47451/tests/dromaeo/cssquery-dojo.html) 01:43:55 INFO - PROCESS | 14426 | RSS: Main: 305266688 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | __start_tp_report 01:43:55 INFO - PROCESS | 14426 | _x_x_mozilla_page_load 01:43:55 INFO - PROCESS | 14426 | _x_x_mozilla_page_load_details 01:43:55 INFO - PROCESS | 14426 | |i|pagename|runs| 01:43:55 INFO - PROCESS | 14426 | |0;dojo.html;3011.217083558274;3032.2116249775067;3035.544668299755;3066.23344163959;3080.4147212029725;5170.353705786777;5206.479352064792;5230.633855630107;5288;5334.626576139672;5290.629655924087;5305.495977882105;5381.031414345416;5407.594430417725;5448.046591846431;4720.64595155363;4762.785674644632;4793.08931303053;4841.346418233528;4894.388201474824;4757.833475828346;4842.491538388471;4986.052649996507;5001.874953126183;5003.724795136284;4897.33885925399;4934.4572097069295;4934.901301973959;4953.653244272902;4954.355933728628;4722.952770472286;4755.310479980383;4786.880327991793;4794.041191761662;4858.732769697665;4718.952810471886;4719.480857105716;4743.051389722069;4820.39745031871;4844.709317440929;5256.526912577886;5278;5311.73441327936;5320.920186197211;5366.9463305366835;5537.889242215173;5648.03983322832;5656.434356564352;5671.376148623648;5675.744591493392;5729.369769325371;5735.741891614845;5742.28221472316;5786.594938354317;5851.4148585141575;5336.146216605363;5378.919316210299;5380;5381.919271210935;5383.488568585978;5642.830715078472;5655.491005809415;5657.066584013674;5777.942220577825;5787.652740835563;5480;5482.972585137049;5487.780488780404;5543.722813859291;5549.19536667181;5549.38956714761;5550.222968784374;5557.472040156179;5558.221848941152;5559.193916882029;5437.864053398617;5530.198121272473;5531.944680553223;5587.497125258747;5597.524210442158;5596.27248457706;5650.378458369576;5660.29246344207;5660.5471562274915;5667.4332566743;4322.308430651081;4356.302991521405;4357.934630980538;4363.650907927358;4410.492793328808;3867.0332416895776;3875.3218186817194;3876.3604005339366;3941.8620348287673;3951.1900060487533;5353.2773075634295;5435.701036443033;5445.428230035868;5466.398696143421;5532.86167845799;5686.175504551817;5723.227364305849;5739.8565035874435;5750.137479378043;5752.884942301131;5262.184361423985;5439.809606663748;5445.455454454602;5454.290942177491;5459.454054594588;4895.020995800783;4949.257611358325;4953.578945789576;4965.081459929852;5027.170516864677;5849.151872978395;5858.56060795442;5859.150423188614;5860.560457965669;5861.736221869941;5572.832815015556;5638.915416268677;5696.658200507983;5700.144978253295;5700.914486282792;5849.502792262535;5897.823065308047;5952.5237980960555;5967.582269241111;5978.551608629283;5616.915746263728;5618.803341883015;5632.211490391266;5633.8591535211945;5634.830955071355;5534;5719.771209151754;5725.828225153252;5726.942730572808;5728.198052272768;5508.091164957737;5538.086215774513;5565.0817615094675;5598.10430331145;5650.576206784425;5515.806946756844;5551.750171242223;5563.666180029211;5568.860778480571;5570.832875013756;5344.25180474726;5501.422350653123;5566.332040155207;5567.137093750475;5645.294338207724;5584.888302234014;5656.123300888369;5657.745401457027;5689.772409103755;5750.022496175577;5541.307336582927;5545.2513910623165;5547.611667183259;5551.22282880389;5561.916571251353 01:43:55 INFO - PROCESS | 14426 | |1;ext.html;555.4261040758194;571.6170165988789;612.6354818882763;614.9815505534833;632.64255695532;31314.843425782867;43765.12469750607;44682.77658611711;44693;44760;44682.77658611703;45063.32405013927;45215;45237;45238;8262;9014.504202268872;9160.129787670161;9177.311701622371;9179.770505737342;9937.90062099377;10227.642032528864;10236.897631023707;10508.947455262713;10520.631777887775;10949.616763413282;11017.614383496579;11021.228514004022;11138.888611113907;11139.72150696235;10716.892831071706;10788.838167427533;10800.45997700112;10802.081823045051;10837.295575787548;10670.733231669194;10724.195685323553;10735.785284294348;10752.462376881125;10753.085987691056;42442.57557424417;42823;42830.78584607088;42848.57151428477;42855.78572107151;3548.8935331940083;3686.631336866292;3717.423799311111;3866.729328946975;4050.534188568323;31537.84231078853;31805.840970794998;33354.83322583396;33415.331693365995;33454.163645908804;3935.1342704605227;4005.6995725320717;4019.9598004019876;4142.378643203544;4191.203671302474;263352;265014;265813.670931646;266068;267165;52491;52505.2124218137;52763.472365276626;52765.736171318895;57627.423725763045;59610.70194648999;59866.70066649726;60175;60216;60248;17018.744718829228;17200.053997030285;17211.483655490356;17216.05311707868;17219.741703874453;10853.728656783485;12734.80897786534;12763.106582539318;12874.935625322001;13178.341082946006;15428.845711542965;15601.219939003004;15617.609559761097;15634.218289085728;15792.842071579136;5844.970775146182;5918.260217472816;5929.258842644669;5936.851578710481;6075.513958883279;930.8138372325562;944.2446043165578;969.2439896880445;1053.3521884041259;1074.1460538871615;30980;31397.215069623442;31441.21396965094;31573;31771.84332922911;1063.8829728729834;1089.9782004360027;1099.5601759296346;1128.8306753986967;1195.1036722458157;16000.119993400474;16210.211706102116;16443.917780411022;16452.83547164537;16785.160741962853;535880;540007;540195;541137;541179;56942;57006.7149664249;57076;57130.42869571251;57144;1203.8134089216185;1265.5760320292804;1294.8705129486975;1327.3296985022503;1341.3226320708175;29920.716649503847;32592.674073258964;32666.34667306593;32883.8355808229;33140.1714957128;47052.52947470482;52292.73853630707;53837.73081134569;53899;58019.709901450224;450572;455832;456623;458046;458156;26815.329616758958;27297.454050918474;27449.17652470429;27844.860775695994;28259.2935176614;46024;46569.3014604788;46619;46964;47544.286835698185;38738;39410.80294598509;39514.40728389134;40846;41317.793411032755;38881.02797430087;38951.220975580894;39080.41379379255;39485.80257098696;41241.58758412377;38572.421413678254;39503.407448888865;39849;40056.1988760229;40076.398854017796;33017.17457063593;37850.43224351692;37997.620023799405;38316;39371.212575747755;27635.309117271423;28301.150965471068;28346.43307133887;28371;28395 01:43:55 INFO - PROCESS | 14426 | |2;jquery.html;386.8065967016492;399.88803135122174;404.55296896928894;407.7899881560996;423.4727763933903;15391.538253852388;19046.142923568437;19093.99999999998;19281.421557353286;20837.583248335024;18891.433257002278;19195.13621887015;19867;19940.9062048485;19992.40022799315;5175.948240517584;5623.550115990732;5863.501602363783;5878.911816322759;5932.288125424955;5804.332501762288;5804.912926306109;5878;5879.0593505039205;5882.852928676796;5611.607187496877;5701.572382071361;5744.712764361786;5748.281464816898;5825.330087039981;5607.4112218217315;5751.309842818868;5785.768569257225;5801.680907550083;5807.0998995155815;5082.593392528589;5131.461196574381;5137.280780690707;5143.12566863634;5176.611754118456;24211.152609658577;24222.152224672227;24231.151909683078;24281.757182428126;24437.266881993568;2341.1087307175626;2400.8919598618104;2450.8774561271866;2516.345750104986;2588.3658503666534;13873.4450621975;14706.852931470656;14837.183954882477;14888.925555372154;15265.160416177107;1895.668258054835;1902.3817259390753;1917.7274929208932;1962.6369121712528;2002.9298974535966;84227.15772842255;86074.56962715146;86850.5657471715;87242.12757872403;87459;10438.947805260925;10604;10769.569217231223;10837.729056773644;11196.66410007701;15419.768703469457;15424.845751542565;15456.536303910902;15464.304106315243;15472.07167569927;7741.458097933203;8013.759587212393;8023.759287221393;8220.054693710299;8229.711960081368;7496.987906632535;7497.362724168507;7547.396208303323;7772.417068719868;7789.64946577394;6529.771457998947;6633.303503132198;6650.70071846768;6679.732810687518;6796.516388358206;1917.6835822089479;1926.3161577640017;1934.7775005874223;1980.2969945669367;2006.3278801601627;746.8804991201382;841.4488510025943;850.1625898489982;1009.1321463541349;1030.0369154840248;18920;20283;20328.288509902377;20363.389098327072;20363.4909127273;1003.3227571389283;1032.9431881246453;1037.6835065305154;1054.2040759226734;1078.029773204107;10415.479226038668;10885.564577416973;11297.47214568109;11554.59558915434;11561.190716649753;443611;447300;449919;452839;453667;95485;95494.52252738691;96753.51623241979;97500.51249743706;102039.489802552;1018.6078359831519;1018.8013337399284;1037.699067270483;1041.8541404203418;1071.6195750508614;17204.397846075328;17250.65498690044;18118;18319.35882244115;18571.44285671432;40309.798451007555;41739;43033.354499681904;43566.56433435752;45690.543094569955;56843;57471;57663.423365765804;57867.71066144642;59931;8623;8722.476651400682;8813.515256660945;8825.558722063999;8896.39864824312;11996.76006479883;13082.803757943448;13157.407916643966;13320.134191277535;13723.656908577366;11280.153988450735;11374.146938979775;11516.769664606829;11718.472668730099;11850.585229516926;11645.534178632759;11816.881831181578;11847.930989881219;11897.583584574499;12169.452374643339;11758.470868810753;11932.164748467523;12049.759004820029;12232.388380580758;12406.689832754253;8670.956645216733;8720.476771393736;8731.65073397057;8895.332850036144;9277.536123193679;9251.121143491358;11340;11786.81209717402;12280.508779648713;12409.131360804657 01:43:55 INFO - PROCESS | 14426 | |3;mootools.html;1403.0319079834917;1446.4214314274288;1528.7019031288896;1723.9655206895854;1745.6683230186263;8109.229623185803;8747.91252087479;8807.823843523125;9477.336586438953;9479.383840050392;8945.418547794388;9339.533023348822;9345.205657519102;9348.953255233731;9349.158575728183;2480.293116461813;2498.8250822442437;2516.4967006598663;2517.7859881909967;2519.6598459207958;2072.678734796109;2365.8343915925893;2378.8096952243777;2448.338948483908;2456.5823809952353;2460.9261722148362;2484.913028044019;2485.962710559343;2489.0914816092086;2491.489244704831;2310.803581695558;2339.7660233976467;2341.590221711194;2343.871087090209;2346.495503466754;2326.069572171128;2351.5179388225374;2371.9644205336936;2372.833901626887;2379.2862141357696;9298.53507324631;9325.720228393158;9375.76560585984;9401.435913845191;9427.48148851813;5282.128448805943;5444.101723215665;5451.127819548893;5457.454254574551;5459.808906688287;3431.5696562545922;3504.26410453805;3570.785752854837;3591.838367273453;3614.8011859347725;1123.3709122891207;1161.1581603337513;1184.9881501184964;1204.0608325506118;1210.4431961297726;12885.098043136984;12887.935560322232;12890.226586404751;12891.80662290057;12900.935495322557;4418.150293060958;4731.621470282369;4762.928556071662;4781.617470602343;4784.952150478521;10862.45687715611;10869.728256793644;10884.564617415374;10885.619003335003;10897.182711296684;5476.972615136899;5481.2874326338115;5484.506394424521;5486.79290556085;5490.286262785812;4958.727270000185;5022.648414610942;5034.244863270465;5057.064443078042;5079.949200508022;4080.5833642318626;4121.216968775923;4173.582641735863;4273.316269396943;4391.912161756747;1203.8735932726938;1271.7329360834144;1299.7335546213003;1319.9868001319865;1326.7943468762357;924.5515924776478;929.3328148214532;940.5673390240567;944.4427787605346;968.1431932739436;6111.297200821962;6279.309275979638;6348.8412789679705;6349.904751428732;6405.071264666597;961.8364877970762;972.9902700973041;976.4434272464769;983.3853841349156;998.4358837256926;4186.413902053715;4207.368894665825;4224.176285624274;4277.123189746095;4463.486699029653;10832.350058996486;11042.834357484644;11113.11095112389;11129.666110016713;11132.10943124548;10703.892961070289;11010.834837477283;11024.834627480594;11033.172512061628;11171.776564468664;7898.407619428451;7914.24814642608;7915.12933577306;7980.321672657773;7984.600769961478;5940.1683764272175;5959.612625179349;5979.820605381846;6061.393860613904;6130.448259656652;10093.69718908434;10097.596096156072;10114.747131321776;10122.493875306058;10260.538275777459;10634.73413164677;10815.45922703846;10846.349219046882;10966.616168434066;10970.067544258827;8103.716369927024;8223.424360294723;8271.33829293667;8283.58582070882;8349.415540912321;8975.28197744192;9050.547472626211;9057.094290571154;9061.320400969822;9066.63733450681;7722;7737.883931740916;7760.146383897654;7988.12130665638;8329.458585192186;7502.849943001001;7517.548947063194;7703.114141873756;7850.725224617111;7918.445708800559;6882.724691012303;6938.410235129869;6939.757108501179;6945.236024037453;6953;5190.766415511386;5215.739213039257;5253.185756207794;5272.947270527246;5368.489993450616;5174.094533456705;5209.400918894224;5224.190250511177;5370.248165256789;5387.946120538744 01:43:55 INFO - PROCESS | 14426 | |4;prototype.html;316.54891779214614;333.49975037443835;340.3210594863247;340.465469213335;341.56450525579896;5077.822276220333;5176.7929282828645;5198.636095473318;5277.28756617856;5281.788728450862;5146.382434107913;5156.974215128919;5223.608229382802;5248.475152484759;5251.501107394801;4116.855910043149;4125.64931980782;4144.466547377459;4164.292070348048;4172.1864236473975;3999.180168065554;4125.381192821086;4150;4161.895952601194;4174.540800511942;4086.693497987663;4097.938530922039;4101.856435024774;4102.671786257092;4123.402106694528;4079.5716449772644;4089.773068079579;4127;4131.855385061538;4137.81379837905;3602.6397360264027;3671.4125739881492;3675.9448608270895;3704.240630670706;3709.4621279914263;5088.262201980692;5097.7960881564695;5102.821401250939;5105.795768169269;5111.463296353866;4750.358701575277;4759.143354196243;4768.687186714441;4781.685036614924;4789.70677916964;5393.352797664266;5448.155535891903;5480.890382192334;5509.917351239735;5529.557635389199;4054.979725101385;4057.6550993165615;4066.755994640331;4070.6946978976694;4080.510338759338;5710.7430165642645;5738.4835364816945;5753.741081651336;5760.36635970043;5796.159556864273;4013.9197216055513;4141.151064031867;4159.916801664011;4162.271602469556;4197.5172855122055;4198.475190601175;4224.887556221889;4227.788610569459;4228.873133805991;4239.321708526621;4746.311784791255;4757.785899634525;4780.306855506002;4781.067691800135;4784.234522476387;5392.595555333287;5507.531859791962;5560.527355174855;5598.832035038874;5615.606907516516;1362.5776009437106;1419.7657386531107;1458.2781523146111;1475.3066058952274;1478.6746915678534;993.8260804359209;1018.1447584029303;1018.954147063384;1038.4236748604446;1042.4370839746603;5276.736163191825;5281;5282.445343238905;5298.205269209572;5301.356579460303;5560.443955604407;5568.359638641608;5568.638038527482;5586.972065139648;5591.776328946878;5169.630048037273;5246.554042906396;5275.707451674364;5373.274607927958;5379.569634429236;2571.4642366745725;2610.4126571521256;2630.1320564213765;2651.4166883285648;2653.7080921098664;5051.419086804991;5088.77100530477;5096.146158151447;5107.8723031923755;5115.565176959925;4870.561649451566;5017.698938063728;5042.142835717937;5065;5076.314697515862;5042.4705405931845;5073.822416215415;5078.365204349457;5110.105731496973;5111.693298402033;4862.857228551358;4937.39534651247;4949.406071271326;4950.950490495049;4965.329680493087;4620.745858977789;4858.27125931099;4882.853514394574;4898.191798353232;4907.607391408607;5045.493382099406;5053.267276244997;5057.216131499624;5076.847694569169;5082.348236822973;3809.4857194279525;3832.6011005983173;3841.2317536492255;3857.0550215197454;3869.1681288523105;4855.75721213945;4887.873774907926;4897.510248975074;4932.4081110267;5060.84817455477;4860.392450943632;4878.756062196947;4924.310596516419;4935.506449355035;4947.233178857283;4828.734419606815;4918.655694101379;4923.679960802537;4938.123513065012;4957.413627639121;4759.476457589593;4896.436909755281;4922.852314430573;4934.259861020875;4946.353700770071;4739.952600473951;4855.843701593993;4857.275667138115;4930.211166213388;4936.407631084293;4739.312799644102;4773.260144677581;4797.928031079467;4810.759462026955;4885.389326334208 01:43:55 INFO - PROCESS | 14426 | |5;yui.html;746.8543633991374;759.3469616130127;792.6155814430002;800.7877912353231;839.680921249925;15048.699026019473;15600.531984040468;15603.531894043168;15651.21743912804;15655.686886262281;15307.234638268097;15445.22773861308;15466;15477.690446191098;15520.379184832622;656.3469347998724;784.8273379856422;794.7258195922412;814.8818421328933;817.6606708216083;789.6328207383566;791.4815795653856;793.1275596843454;796.8167321516055;797.2585495489193;844.5270648436864;845.3026253340987;845.6490556419079;845.8477474054667;846.71635002274;687.5118665747325;814.775936617432;830.2278880641002;835.5362773660615;839.8824164616958;614.9938500615026;615.6460035479572;616.1097214525013;617.0435824472095;620.4354037825602;15296.541103766906;15312.617184570363;15353.15557644329;15369;15378.692426151414;13900.652483687887;14187;14236.857631423656;14293.428262869473;14430;11688.707782305426;11818.881811181951;12076.637700868989;12195.573154939624;12504.249745015237;4460.955390446086;4487.326900964848;4634.721916684976;4644.210484217691;4677.462091859412;74271.99999999946;74863;77020.61489692572;78809.14885639295;79032;11455.198136130557;11459.713507162222;11625.973767390858;11680.123990700731;11759;21173.15307387718;21197.152113915272;21198.15207391718;21199.152033918777;21234;8158.877616835753;8397.832043359098;8399.580020998925;8417.98984121897;8581.442206256574;4677.342635167296;4699.083678682624;4778.474367819537;4898.314236006961;4927.975360123248;3223.9677603223668;3309.12308238317;3349.112485191426;3358.6809253120914;3361.680640339213;522.2401405954328;592.831043152708;600.3996003996004;604.6432604763123;605.0410099991562;737.4395459450856;737.4763917618444;750.7034721285062;770.7456539342004;777.1257335497565;9763.218942484584;9837.884854756261;10373.27387082912;10598.841017384746;10654.307470014424;649.7011374767566;665.3114026982147;683.7948615415355;711.2425267090532;728.4682182007165;4639.188142075123;4842.346283251716;4987.725675087905;5040.697558146523;5068.795074142759;102922.48538757258;126719.36640316923;126737;127249;130362;61556;92053;104220.47889760503;107423.81219004921;107818;75731;76717;78620.60689696515;84360.57819710863;85451.57274213589;9437.480938548171;9729.011105446732;10019.499025048864;10050.145737612092;10061.748456288651;53144.4685553155;53228.4677153239;53836;54924.725376372866;55915;72361;75687.62156189184;76564;76817.23182768101;78544;11535.76928461443;11687.474063667325;12592;12708.682282943;13287.734245314847;13633.454661813417;13827.585172444842;13881;13988.650283742987;14631.634209144857;13801.585952421041;14069.507567235098;14401;15763.448279310169;15784.052956822168;13480.730385392433;13737.450501979809;13895.37470813836;14462.05996610217;14656.706865862836;13399.665008374868;13736.862631373559;14414.207218603078;14417.351219194952;14616;11191.216614837209;11363.886361136283;12031.939840301093;12032.518699252281;12194.939025304817;11807.704807379885;11947.761044778881;12129;12312.13815032939;12455.501779928702 01:43:55 INFO - PROCESS | 14426 | __end_tp_report 01:43:55 INFO - PROCESS | 14426 | __start_cc_report 01:43:55 INFO - PROCESS | 14426 | _x_x_mozilla_cycle_collect,1056 01:43:55 INFO - PROCESS | 14426 | __end_cc_report 01:43:55 INFO - PROCESS | 14426 | __startTimestamp1472201035050__endTimestamp 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | ------- Summary: start ------- 01:43:55 INFO - PROCESS | 14426 | Number of tests: 6 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#0] dojo.html Cycles:165 Average:5283.26 Median:5530.20 stddev:596.70 (10.8%) stddev-sans-first:571.29 01:43:55 INFO - PROCESS | 14426 | Values: 3011.2 3032.2 3035.5 3066.2 3080.4 5170.4 5206.5 5230.6 5288.0 5334.6 5290.6 5305.5 5381.0 5407.6 5448.0 4720.6 4762.8 4793.1 4841.3 4894.4 4757.8 4842.5 4986.1 5001.9 5003.7 4897.3 4934.5 4934.9 4953.7 4954.4 4723.0 4755.3 4786.9 4794.0 4858.7 4719.0 4719.5 4743.1 4820.4 4844.7 5256.5 5278.0 5311.7 5320.9 5366.9 5537.9 5648.0 5656.4 5671.4 5675.7 5729.4 5735.7 5742.3 5786.6 5851.4 5336.1 5378.9 5380.0 5381.9 5383.5 5642.8 5655.5 5657.1 5777.9 5787.7 5480.0 5483.0 5487.8 5543.7 5549.2 5549.4 5550.2 5557.5 5558.2 5559.2 5437.9 5530.2 5531.9 5587.5 5597.5 5596.3 5650.4 5660.3 5660.5 5667.4 4322.3 4356.3 4357.9 4363.7 4410.5 3867.0 3875.3 3876.4 3941.9 3951.2 5353.3 5435.7 5445.4 5466.4 5532.9 5686.2 5723.2 5739.9 5750.1 5752.9 5262.2 5439.8 5445.5 5454.3 5459.5 4895.0 4949.3 4953.6 4965.1 5027.2 5849.2 5858.6 5859.2 5860.6 5861.7 5572.8 5638.9 5696.7 5700.1 5700.9 5849.5 5897.8 5952.5 5967.6 5978.6 5616.9 5618.8 5632.2 5633.9 5634.8 5534.0 5719.8 5725.8 5726.9 5728.2 5508.1 5538.1 5565.1 5598.1 5650.6 5515.8 5551.8 5563.7 5568.9 5570.8 5344.3 5501.4 5566.3 5567.1 5645.3 5584.9 5656.1 5657.7 5689.8 5750.0 5541.3 5545.3 5547.6 5551.2 5561.9 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#1] ext.html Cycles:180 Average:58359.79 Median:21302.36 stddev:115753.05 (543.4%) stddev-sans-first:115996.41 01:43:55 INFO - PROCESS | 14426 | Values: 555.4 571.6 612.6 615.0 632.6 31314.8 43765.1 44682.8 44693.0 44760.0 44682.8 45063.3 45215.0 45237.0 45238.0 8262.0 9014.5 9160.1 9177.3 9179.8 9937.9 10227.6 10236.9 10508.9 10520.6 10949.6 11017.6 11021.2 11138.9 11139.7 10716.9 10788.8 10800.5 10802.1 10837.3 10670.7 10724.2 10735.8 10752.5 10753.1 42442.6 42823.0 42830.8 42848.6 42855.8 3548.9 3686.6 3717.4 3866.7 4050.5 31537.8 31805.8 33354.8 33415.3 33454.2 3935.1 4005.7 4020.0 4142.4 4191.2 263352.0 265014.0 265813.7 266068.0 267165.0 52491.0 52505.2 52763.5 52765.7 57627.4 59610.7 59866.7 60175.0 60216.0 60248.0 17018.7 17200.1 17211.5 17216.1 17219.7 10853.7 12734.8 12763.1 12874.9 13178.3 15428.8 15601.2 15617.6 15634.2 15792.8 5845.0 5918.3 5929.3 5936.9 6075.5 930.8 944.2 969.2 1053.4 1074.1 30980.0 31397.2 31441.2 31573.0 31771.8 1063.9 1090.0 1099.6 1128.8 1195.1 16000.1 16210.2 16443.9 16452.8 16785.2 535880.0 540007.0 540195.0 541137.0 541179.0 56942.0 57006.7 57076.0 57130.4 57144.0 1203.8 1265.6 1294.9 1327.3 1341.3 29920.7 32592.7 32666.3 32883.8 33140.2 47052.5 52292.7 53837.7 53899.0 58019.7 450572.0 455832.0 456623.0 458046.0 458156.0 26815.3 27297.5 27449.2 27844.9 28259.3 46024.0 46569.3 46619.0 46964.0 47544.3 38738.0 39410.8 39514.4 40846.0 41317.8 38881.0 38951.2 39080.4 39485.8 41241.6 38572.4 39503.4 39849.0 40056.2 40076.4 33017.2 37850.4 37997.6 38316.0 39371.2 27635.3 28301.2 28346.4 28371.0 28395.0 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#2] jquery.html Cycles:180 Average:28459.19 Median:24226.65 stddev:74558.12 (307.8%) stddev-sans-first:74737.48 01:43:55 INFO - PROCESS | 14426 | Values: 386.8 399.9 404.6 407.8 423.5 15391.5 19046.1 19094.0 19281.4 20837.6 18891.4 19195.1 19867.0 19940.9 19992.4 5175.9 5623.6 5863.5 5878.9 5932.3 5804.3 5804.9 5878.0 5879.1 5882.9 5611.6 5701.6 5744.7 5748.3 5825.3 5607.4 5751.3 5785.8 5801.7 5807.1 5082.6 5131.5 5137.3 5143.1 5176.6 24211.2 24222.2 24231.2 24281.8 24437.3 2341.1 2400.9 2450.9 2516.3 2588.4 13873.4 14706.9 14837.2 14888.9 15265.2 1895.7 1902.4 1917.7 1962.6 2002.9 84227.2 86074.6 86850.6 87242.1 87459.0 10438.9 10604.0 10769.6 10837.7 11196.7 15419.8 15424.8 15456.5 15464.3 15472.1 7741.5 8013.8 8023.8 8220.1 8229.7 7497.0 7497.4 7547.4 7772.4 7789.6 6529.8 6633.3 6650.7 6679.7 6796.5 1917.7 1926.3 1934.8 1980.3 2006.3 746.9 841.4 850.2 1009.1 1030.0 18920.0 20283.0 20328.3 20363.4 20363.5 1003.3 1032.9 1037.7 1054.2 1078.0 10415.5 10885.6 11297.5 11554.6 11561.2 443611.0 447300.0 449919.0 452839.0 453667.0 95485.0 95494.5 96753.5 97500.5 102039.5 1018.6 1018.8 1037.7 1041.9 1071.6 17204.4 17250.7 18118.0 18319.4 18571.4 40309.8 41739.0 43033.4 43566.6 45690.5 56843.0 57471.0 57663.4 57867.7 59931.0 8623.0 8722.5 8813.5 8825.6 8896.4 11996.8 13082.8 13157.4 13320.1 13723.7 11280.2 11374.1 11516.8 11718.5 11850.6 11645.5 11816.9 11847.9 11897.6 12169.5 11758.5 11932.2 12049.8 12232.4 12406.7 8671.0 8720.5 8731.7 8895.3 9277.5 9251.1 11340.0 11786.8 12280.5 12409.1 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#3] mootools.html Cycles:180 Average:5988.74 Median:4990.69 stddev:3409.02 (68.3%) stddev-sans-first:3401.17 01:43:55 INFO - PROCESS | 14426 | Values: 1403.0 1446.4 1528.7 1724.0 1745.7 8109.2 8747.9 8807.8 9477.3 9479.4 8945.4 9339.5 9345.2 9349.0 9349.2 2480.3 2498.8 2516.5 2517.8 2519.7 2072.7 2365.8 2378.8 2448.3 2456.6 2460.9 2484.9 2486.0 2489.1 2491.5 2310.8 2339.8 2341.6 2343.9 2346.5 2326.1 2351.5 2372.0 2372.8 2379.3 9298.5 9325.7 9375.8 9401.4 9427.5 5282.1 5444.1 5451.1 5457.5 5459.8 3431.6 3504.3 3570.8 3591.8 3614.8 1123.4 1161.2 1185.0 1204.1 1210.4 12885.1 12887.9 12890.2 12891.8 12900.9 4418.2 4731.6 4762.9 4781.6 4785.0 10862.5 10869.7 10884.6 10885.6 10897.2 5477.0 5481.3 5484.5 5486.8 5490.3 4958.7 5022.6 5034.2 5057.1 5079.9 4080.6 4121.2 4173.6 4273.3 4391.9 1203.9 1271.7 1299.7 1320.0 1326.8 924.6 929.3 940.6 944.4 968.1 6111.3 6279.3 6348.8 6349.9 6405.1 961.8 973.0 976.4 983.4 998.4 4186.4 4207.4 4224.2 4277.1 4463.5 10832.4 11042.8 11113.1 11129.7 11132.1 10703.9 11010.8 11024.8 11033.2 11171.8 7898.4 7914.2 7915.1 7980.3 7984.6 5940.2 5959.6 5979.8 6061.4 6130.4 10093.7 10097.6 10114.7 10122.5 10260.5 10634.7 10815.5 10846.3 10966.6 10970.1 8103.7 8223.4 8271.3 8283.6 8349.4 8975.3 9050.5 9057.1 9061.3 9066.6 7722.0 7737.9 7760.1 7988.1 8329.5 7502.8 7517.5 7703.1 7850.7 7918.4 6882.7 6938.4 6939.8 6945.2 6953.0 5190.8 5215.7 5253.2 5272.9 5368.5 5174.1 5209.4 5224.2 5370.2 5387.9 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#4] prototype.html Cycles:180 Average:4420.21 Median:4884.12 stddev:1230.76 (25.2%) stddev-sans-first:1195.05 01:43:55 INFO - PROCESS | 14426 | Values: 316.5 333.5 340.3 340.5 341.6 5077.8 5176.8 5198.6 5277.3 5281.8 5146.4 5157.0 5223.6 5248.5 5251.5 4116.9 4125.6 4144.5 4164.3 4172.2 3999.2 4125.4 4150.0 4161.9 4174.5 4086.7 4097.9 4101.9 4102.7 4123.4 4079.6 4089.8 4127.0 4131.9 4137.8 3602.6 3671.4 3675.9 3704.2 3709.5 5088.3 5097.8 5102.8 5105.8 5111.5 4750.4 4759.1 4768.7 4781.7 4789.7 5393.4 5448.2 5480.9 5509.9 5529.6 4055.0 4057.7 4066.8 4070.7 4080.5 5710.7 5738.5 5753.7 5760.4 5796.2 4013.9 4141.2 4159.9 4162.3 4197.5 4198.5 4224.9 4227.8 4228.9 4239.3 4746.3 4757.8 4780.3 4781.1 4784.2 5392.6 5507.5 5560.5 5598.8 5615.6 1362.6 1419.8 1458.3 1475.3 1478.7 993.8 1018.1 1019.0 1038.4 1042.4 5276.7 5281.0 5282.4 5298.2 5301.4 5560.4 5568.4 5568.6 5587.0 5591.8 5169.6 5246.6 5275.7 5373.3 5379.6 2571.5 2610.4 2630.1 2651.4 2653.7 5051.4 5088.8 5096.1 5107.9 5115.6 4870.6 5017.7 5042.1 5065.0 5076.3 5042.5 5073.8 5078.4 5110.1 5111.7 4862.9 4937.4 4949.4 4951.0 4965.3 4620.7 4858.3 4882.9 4898.2 4907.6 5045.5 5053.3 5057.2 5076.8 5082.3 3809.5 3832.6 3841.2 3857.1 3869.2 4855.8 4887.9 4897.5 4932.4 5060.8 4860.4 4878.8 4924.3 4935.5 4947.2 4828.7 4918.7 4923.7 4938.1 4957.4 4759.5 4896.4 4922.9 4934.3 4946.4 4740.0 4855.8 4857.3 4930.2 4936.4 4739.3 4773.3 4797.9 4810.8 4885.4 01:43:55 INFO - PROCESS | 14426 | 01:43:55 INFO - PROCESS | 14426 | [#5] yui.html Cycles:180 Average:20996.77 Median:4561.02 stddev:30308.22 (664.5%) stddev-sans-first:30355.11 01:43:55 INFO - PROCESS | 14426 | Values: 746.9 759.3 792.6 800.8 839.7 15048.7 15600.5 15603.5 15651.2 15655.7 15307.2 15445.2 15466.0 15477.7 15520.4 656.3 784.8 794.7 814.9 817.7 789.6 791.5 793.1 796.8 797.3 844.5 845.3 845.6 845.8 846.7 687.5 814.8 830.2 835.5 839.9 615.0 615.6 616.1 617.0 620.4 15296.5 15312.6 15353.2 15369.0 15378.7 13900.7 14187.0 14236.9 14293.4 14430.0 11688.7 11818.9 12076.6 12195.6 12504.2 4461.0 4487.3 4634.7 4644.2 4677.5 74272.0 74863.0 77020.6 78809.1 79032.0 11455.2 11459.7 11626.0 11680.1 11759.0 21173.2 21197.2 21198.2 21199.2 21234.0 8158.9 8397.8 8399.6 8418.0 8581.4 4677.3 4699.1 4778.5 4898.3 4928.0 3224.0 3309.1 3349.1 3358.7 3361.7 522.2 592.8 600.4 604.6 605.0 737.4 737.5 750.7 770.7 777.1 9763.2 9837.9 10373.3 10598.8 10654.3 649.7 665.3 683.8 711.2 728.5 4639.2 4842.3 4987.7 5040.7 5068.8 102922.5 126719.4 126737.0 127249.0 130362.0 61556.0 92053.0 104220.5 107423.8 107818.0 75731.0 76717.0 78620.6 84360.6 85451.6 9437.5 9729.0 10019.5 10050.1 10061.7 53144.5 53228.5 53836.0 54924.7 55915.0 72361.0 75687.6 76564.0 76817.2 78544.0 11535.8 11687.5 12592.0 12708.7 13287.7 13633.5 13827.6 13881.0 13988.7 14631.6 13801.6 14069.5 14401.0 15763.4 15784.1 13480.7 13737.5 13895.4 14462.1 14656.7 13399.7 13736.9 14414.2 14417.4 14616.0 11191.2 11363.9 12031.9 12032.5 12194.9 11807.7 11947.8 12129.0 12312.1 12455.5 01:43:55 INFO - PROCESS | 14426 | -------- Summary: end -------- 01:43:55 INFO - PROCESS | 14426 | 01:43:56 INFO - TEST-INFO | 14426: exit 0 01:43:56 INFO - TEST-OK | dromaeo_css | took 1115596ms 01:43:56 INFO - TEST-START | kraken 01:43:56 INFO - operating with platform_type : linux_ 01:43:56 INFO - Initialising browser for kraken test... 01:43:56 INFO - TEST-INFO | started process 14730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEeLpLW/profile http://localhost:47451/getInfo.html) 01:44:00 INFO - PROCESS | 14730 | __metrics Screen width/height:1600/1200 01:44:00 INFO - PROCESS | 14730 | colorDepth:24 01:44:00 INFO - PROCESS | 14730 | Browser inner width/height: 1024/697 01:44:00 INFO - PROCESS | 14730 | __metrics 01:44:00 INFO - PROCESS | 14730 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:44:00 INFO - PROCESS | 14730 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:44:01 INFO - PROCESS | 14730 | console.error: PushService: 01:44:01 INFO - PROCESS | 14730 | stateChangeProcessEnqueue: Error transitioning state 01:44:01 INFO - PROCESS | 14730 | UnknownError 01:44:02 INFO - TEST-INFO | 14730: exit 0 01:44:02 INFO - Browser initialized. 01:44:02 INFO - Running cycle 1/1 for kraken test... 01:44:02 INFO - TEST-INFO | started process 14813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEeLpLW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:44:14 INFO - PROCESS | 14813 | RSS: Main: 179175424 01:44:14 INFO - PROCESS | 14813 | 01:44:16 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47451/tests/kraken/driver.html?testName=audio-beat-detection) 01:44:17 INFO - PROCESS | 14813 | RSS: Main: 236982272 01:44:17 INFO - PROCESS | 14813 | 01:44:20 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47451/tests/kraken/driver.html?testName=audio-dft) 01:44:20 INFO - PROCESS | 14813 | RSS: Main: 222384128 01:44:20 INFO - PROCESS | 14813 | 01:44:23 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47451/tests/kraken/driver.html?testName=audio-fft) 01:44:24 INFO - PROCESS | 14813 | RSS: Main: 301805568 01:44:24 INFO - PROCESS | 14813 | 01:44:26 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47451/tests/kraken/driver.html?testName=audio-oscillator) 01:44:27 INFO - PROCESS | 14813 | RSS: Main: 212234240 01:44:27 INFO - PROCESS | 14813 | 01:44:29 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47451/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:44:29 INFO - PROCESS | 14813 | RSS: Main: 214056960 01:44:29 INFO - PROCESS | 14813 | 01:44:32 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47451/tests/kraken/driver.html?testName=imaging-darkroom) 01:44:33 INFO - PROCESS | 14813 | RSS: Main: 253747200 01:44:33 INFO - PROCESS | 14813 | 01:44:36 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47451/tests/kraken/driver.html?testName=imaging-desaturate) 01:44:37 INFO - PROCESS | 14813 | RSS: Main: 253353984 01:44:37 INFO - PROCESS | 14813 | 01:44:40 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47451/tests/kraken/driver.html?testName=json-parse-financial) 01:44:40 INFO - PROCESS | 14813 | RSS: Main: 253562880 01:44:40 INFO - PROCESS | 14813 | 01:44:42 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47451/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:44:42 INFO - PROCESS | 14813 | RSS: Main: 215298048 01:44:42 INFO - PROCESS | 14813 | 01:44:44 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:44:45 INFO - PROCESS | 14813 | RSS: Main: 222142464 01:44:45 INFO - PROCESS | 14813 | 01:44:47 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:44:48 INFO - PROCESS | 14813 | RSS: Main: 221917184 01:44:48 INFO - PROCESS | 14813 | 01:44:50 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:44:51 INFO - PROCESS | 14813 | RSS: Main: 222609408 01:44:51 INFO - PROCESS | 14813 | 01:44:54 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:44:54 INFO - PROCESS | 14813 | RSS: Main: 220078080 01:44:54 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | Cycle 1(1): loaded http://localhost:47451/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47451/tests/kraken/driver.html?testName=ai-astar) 01:44:56 INFO - PROCESS | 14813 | RSS: Main: 271564800 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | __start_tp_report 01:44:56 INFO - PROCESS | 14813 | _x_x_mozilla_page_load 01:44:56 INFO - PROCESS | 14813 | _x_x_mozilla_page_load_details 01:44:56 INFO - PROCESS | 14813 | |i|pagename|runs| 01:44:56 INFO - PROCESS | 14813 | |0;ai-astar;112;111;127;112;113;113;112;112;110;110 01:44:56 INFO - PROCESS | 14813 | |1;audio-beat-detection;151;124;161;179;124;143;149;120;183;119 01:44:56 INFO - PROCESS | 14813 | |2;audio-dft;162;164;161;164;162;163;162;161;160;161 01:44:56 INFO - PROCESS | 14813 | |3;audio-fft;74;76;75;177;76;76;76;75;170;76 01:44:56 INFO - PROCESS | 14813 | |4;audio-oscillator;83;83;144;82;82;81;145;83;83;141 01:44:56 INFO - PROCESS | 14813 | |5;imaging-gaussian-blur;118;118;117;118;118;118;117;118;117;117 01:44:56 INFO - PROCESS | 14813 | |6;imaging-darkroom;147;148;147;147;146;147;146;146;146;146 01:44:56 INFO - PROCESS | 14813 | |7;imaging-desaturate;96;95;96;96;96;96;95;95;96;95 01:44:56 INFO - PROCESS | 14813 | |8;json-parse-financial;53;51;53;53;52;53;54;54;50;50 01:44:56 INFO - PROCESS | 14813 | |9;json-stringify-tinderbox;60;61;62;61;61;61;61;61;61;61 01:44:56 INFO - PROCESS | 14813 | |10;stanford-crypto-aes;85;79;79;79;82;83;85;81;79;88 01:44:56 INFO - PROCESS | 14813 | |11;stanford-crypto-ccm;140;127;128;142;125;133;126;125;157;125 01:44:56 INFO - PROCESS | 14813 | |12;stanford-crypto-pbkdf2;163;164;164;169;168;169;164;163;163;163 01:44:56 INFO - PROCESS | 14813 | |13;stanford-crypto-sha256-iterative;65;65;66;64;65;64;65;63;64;63 01:44:56 INFO - PROCESS | 14813 | __end_tp_report 01:44:56 INFO - PROCESS | 14813 | __start_cc_report 01:44:56 INFO - PROCESS | 14813 | _x_x_mozilla_cycle_collect,1695 01:44:56 INFO - PROCESS | 14813 | __end_cc_report 01:44:56 INFO - PROCESS | 14813 | __startTimestamp1472201096531__endTimestamp 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | ------- Summary: start ------- 01:44:56 INFO - PROCESS | 14813 | Number of tests: 14 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#0] ai-astar Cycles:10 Average:113.20 Median:112.00 stddev:4.96 (4.4%) stddev-sans-first:5.24 01:44:56 INFO - PROCESS | 14813 | Values: 112.0 111.0 127.0 112.0 113.0 113.0 112.0 112.0 110.0 110.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#1] audio-beat-detection Cycles:10 Average:145.30 Median:150.00 stddev:23.79 (15.9%) stddev-sans-first:25.14 01:44:56 INFO - PROCESS | 14813 | Values: 151.0 124.0 161.0 179.0 124.0 143.0 149.0 120.0 183.0 119.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.33 (0.8%) stddev-sans-first:1.41 01:44:56 INFO - PROCESS | 14813 | Values: 162.0 164.0 161.0 164.0 162.0 163.0 162.0 161.0 160.0 161.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#3] audio-fft Cycles:10 Average:95.10 Median:76.00 stddev:41.36 (54.4%) stddev-sans-first:43.16 01:44:56 INFO - PROCESS | 14813 | Values: 74.0 76.0 75.0 177.0 76.0 76.0 76.0 75.0 170.0 76.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#4] audio-oscillator Cycles:10 Average:100.70 Median:82.50 stddev:29.44 (35.7%) stddev-sans-first:30.52 01:44:56 INFO - PROCESS | 14813 | Values: 83.0 83.0 144.0 82.0 82.0 81.0 145.0 83.0 83.0 141.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 01:44:56 INFO - PROCESS | 14813 | Values: 118.0 118.0 117.0 118.0 118.0 118.0 117.0 118.0 117.0 117.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 01:44:56 INFO - PROCESS | 14813 | Values: 147.0 148.0 147.0 147.0 146.0 147.0 146.0 146.0 146.0 146.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 01:44:56 INFO - PROCESS | 14813 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:53.00 stddev:1.49 (2.8%) stddev-sans-first:1.56 01:44:56 INFO - PROCESS | 14813 | Values: 53.0 51.0 53.0 53.0 52.0 53.0 54.0 54.0 50.0 50.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:61.00 stddev:0.47 (0.8%) stddev-sans-first:0.33 01:44:56 INFO - PROCESS | 14813 | Values: 60.0 61.0 62.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.50 stddev:3.20 (3.9%) stddev-sans-first:3.20 01:44:56 INFO - PROCESS | 14813 | Values: 85.0 79.0 79.0 79.0 82.0 83.0 85.0 81.0 79.0 88.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#11] stanford-crypto-ccm Cycles:10 Average:132.80 Median:130.50 stddev:10.58 (8.1%) stddev-sans-first:10.90 01:44:56 INFO - PROCESS | 14813 | Values: 140.0 127.0 128.0 142.0 125.0 133.0 126.0 125.0 157.0 125.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:164.00 stddev:2.58 (1.6%) stddev-sans-first:2.64 01:44:56 INFO - PROCESS | 14813 | Values: 163.0 164.0 164.0 169.0 168.0 169.0 164.0 163.0 163.0 163.0 01:44:56 INFO - PROCESS | 14813 | 01:44:56 INFO - PROCESS | 14813 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 01:44:56 INFO - PROCESS | 14813 | Values: 65.0 65.0 66.0 64.0 65.0 64.0 65.0 63.0 64.0 63.0 01:44:56 INFO - PROCESS | 14813 | -------- Summary: end -------- 01:44:56 INFO - PROCESS | 14813 | 01:44:57 INFO - TEST-INFO | 14813: exit 0 01:44:57 INFO - TEST-OK | kraken | took 61680ms 01:44:57 INFO - SUITE-END | took 1177s 01:44:58 INFO - Completed test suite (00:19:37) 01:44:58 INFO - javascript benchmark 01:44:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.217083558274, 3032.2116249775067, 3035.544668299755, 3066.23344163959, 3080.4147212029725, 5170.353705786777, 5206.479352064792, 5230.633855630107, 5288.0, 5334.626576139672, 5290.629655924087, 5305.495977882105, 5381.031414345416, 5407.594430417725, 5448.046591846431, 4720.64595155363, 4762.785674644632, 4793.08931303053, 4841.346418233528, 4894.388201474824, 4757.833475828346, 4842.491538388471, 4986.052649996507, 5001.874953126183, 5003.724795136284, 4897.33885925399, 4934.4572097069295, 4934.901301973959, 4953.653244272902, 4954.355933728628, 4722.952770472286, 4755.310479980383, 4786.880327991793, 4794.041191761662, 4858.732769697665, 4718.952810471886, 4719.480857105716, 4743.051389722069, 4820.39745031871, 4844.709317440929, 5256.526912577886, 5278.0, 5311.73441327936, 5320.920186197211, 5366.9463305366835, 5537.889242215173, 5648.03983322832, 5656.434356564352, 5671.376148623648, 5675.744591493392, 5729.369769325371, 5735.741891614845, 5742.28221472316, 5786.594938354317, 5851.4148585141575, 5336.146216605363, 5378.919316210299, 5380.0, 5381.919271210935, 5383.488568585978, 5642.830715078472, 5655.491005809415, 5657.066584013674, 5777.942220577825, 5787.652740835563, 5480.0, 5482.972585137049, 5487.780488780404, 5543.722813859291, 5549.19536667181, 5549.38956714761, 5550.222968784374, 5557.472040156179, 5558.221848941152, 5559.193916882029, 5437.864053398617, 5530.198121272473, 5531.944680553223, 5587.497125258747, 5597.524210442158, 5596.27248457706, 5650.378458369576, 5660.29246344207, 5660.5471562274915, 5667.4332566743, 4322.308430651081, 4356.302991521405, 4357.934630980538, 4363.650907927358, 4410.492793328808, 3867.0332416895776, 3875.3218186817194, 3876.3604005339366, 3941.8620348287673, 3951.1900060487533, 5353.2773075634295, 5435.701036443033, 5445.428230035868, 5466.398696143421, 5532.86167845799, 5686.175504551817, 5723.227364305849, 5739.8565035874435, 5750.137479378043, 5752.884942301131, 5262.184361423985, 5439.809606663748, 5445.455454454602, 5454.290942177491, 5459.454054594588, 4895.020995800783, 4949.257611358325, 4953.578945789576, 4965.081459929852, 5027.170516864677, 5849.151872978395, 5858.56060795442, 5859.150423188614, 5860.560457965669, 5861.736221869941, 5572.832815015556, 5638.915416268677, 5696.658200507983, 5700.144978253295, 5700.914486282792, 5849.502792262535, 5897.823065308047, 5952.5237980960555, 5967.582269241111, 5978.551608629283, 5616.915746263728, 5618.803341883015, 5632.211490391266, 5633.8591535211945, 5634.830955071355, 5534.0, 5719.771209151754, 5725.828225153252, 5726.942730572808, 5728.198052272768, 5508.091164957737, 5538.086215774513, 5565.0817615094675, 5598.10430331145, 5650.576206784425, 5515.806946756844, 5551.750171242223, 5563.666180029211, 5568.860778480571, 5570.832875013756, 5344.25180474726, 5501.422350653123, 5566.332040155207, 5567.137093750475, 5645.294338207724, 5584.888302234014, 5656.123300888369, 5657.745401457027, 5689.772409103755, 5750.022496175577, 5541.307336582927, 5545.2513910623165, 5547.611667183259, 5551.22282880389, 5561.916571251353], "value": 5242.832057824746, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.4261040758194, 571.6170165988789, 612.6354818882763, 614.9815505534833, 632.64255695532, 31314.843425782867, 43765.12469750607, 44682.77658611711, 44693.0, 44760.0, 44682.77658611703, 45063.32405013927, 45215.0, 45237.0, 45238.0, 8262.0, 9014.504202268872, 9160.129787670161, 9177.311701622371, 9179.770505737342, 9937.90062099377, 10227.642032528864, 10236.897631023707, 10508.947455262713, 10520.631777887775, 10949.616763413282, 11017.614383496579, 11021.228514004022, 11138.888611113907, 11139.72150696235, 10716.892831071706, 10788.838167427533, 10800.45997700112, 10802.081823045051, 10837.295575787548, 10670.733231669194, 10724.195685323553, 10735.785284294348, 10752.462376881125, 10753.085987691056, 42442.57557424417, 42823.0, 42830.78584607088, 42848.57151428477, 42855.78572107151, 3548.8935331940083, 3686.631336866292, 3717.423799311111, 3866.729328946975, 4050.534188568323, 31537.84231078853, 31805.840970794998, 33354.83322583396, 33415.331693365995, 33454.163645908804, 3935.1342704605227, 4005.6995725320717, 4019.9598004019876, 4142.378643203544, 4191.203671302474, 263352.0, 265014.0, 265813.670931646, 266068.0, 267165.0, 52491.0, 52505.2124218137, 52763.472365276626, 52765.736171318895, 57627.423725763045, 59610.70194648999, 59866.70066649726, 60175.0, 60216.0, 60248.0, 17018.744718829228, 17200.053997030285, 17211.483655490356, 17216.05311707868, 17219.741703874453, 10853.728656783485, 12734.80897786534, 12763.106582539318, 12874.935625322001, 13178.341082946006, 15428.845711542965, 15601.219939003004, 15617.609559761097, 15634.218289085728, 15792.842071579136, 5844.970775146182, 5918.260217472816, 5929.258842644669, 5936.851578710481, 6075.513958883279, 930.8138372325562, 944.2446043165578, 969.2439896880445, 1053.3521884041259, 1074.1460538871615, 30980.0, 31397.215069623442, 31441.21396965094, 31573.0, 31771.84332922911, 1063.8829728729834, 1089.9782004360027, 1099.5601759296346, 1128.8306753986967, 1195.1036722458157, 16000.119993400474, 16210.211706102116, 16443.917780411022, 16452.83547164537, 16785.160741962853, 535880.0, 540007.0, 540195.0, 541137.0, 541179.0, 56942.0, 57006.7149664249, 57076.0, 57130.42869571251, 57144.0, 1203.8134089216185, 1265.5760320292804, 1294.8705129486975, 1327.3296985022503, 1341.3226320708175, 29920.716649503847, 32592.674073258964, 32666.34667306593, 32883.8355808229, 33140.1714957128, 47052.52947470482, 52292.73853630707, 53837.73081134569, 53899.0, 58019.709901450224, 450572.0, 455832.0, 456623.0, 458046.0, 458156.0, 26815.329616758958, 27297.454050918474, 27449.17652470429, 27844.860775695994, 28259.2935176614, 46024.0, 46569.3014604788, 46619.0, 46964.0, 47544.286835698185, 38738.0, 39410.80294598509, 39514.40728389134, 40846.0, 41317.793411032755, 38881.02797430087, 38951.220975580894, 39080.41379379255, 39485.80257098696, 41241.58758412377, 38572.421413678254, 39503.407448888865, 39849.0, 40056.1988760229, 40076.398854017796, 33017.17457063593, 37850.43224351692, 37997.620023799405, 38316.0, 39371.212575747755, 27635.309117271423, 28301.150965471068, 28346.43307133887, 28371.0, 28395.0], "value": 20030.730950798872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.8065967016492, 399.88803135122174, 404.55296896928894, 407.7899881560996, 423.4727763933903, 15391.538253852388, 19046.142923568437, 19093.99999999998, 19281.421557353286, 20837.583248335024, 18891.433257002278, 19195.13621887015, 19867.0, 19940.9062048485, 19992.40022799315, 5175.948240517584, 5623.550115990732, 5863.501602363783, 5878.911816322759, 5932.288125424955, 5804.332501762288, 5804.912926306109, 5878.0, 5879.0593505039205, 5882.852928676796, 5611.607187496877, 5701.572382071361, 5744.712764361786, 5748.281464816898, 5825.330087039981, 5607.4112218217315, 5751.309842818868, 5785.768569257225, 5801.680907550083, 5807.0998995155815, 5082.593392528589, 5131.461196574381, 5137.280780690707, 5143.12566863634, 5176.611754118456, 24211.152609658577, 24222.152224672227, 24231.151909683078, 24281.757182428126, 24437.266881993568, 2341.1087307175626, 2400.8919598618104, 2450.8774561271866, 2516.345750104986, 2588.3658503666534, 13873.4450621975, 14706.852931470656, 14837.183954882477, 14888.925555372154, 15265.160416177107, 1895.668258054835, 1902.3817259390753, 1917.7274929208932, 1962.6369121712528, 2002.9298974535966, 84227.15772842255, 86074.56962715146, 86850.5657471715, 87242.12757872403, 87459.0, 10438.947805260925, 10604.0, 10769.569217231223, 10837.729056773644, 11196.66410007701, 15419.768703469457, 15424.845751542565, 15456.536303910902, 15464.304106315243, 15472.07167569927, 7741.458097933203, 8013.759587212393, 8023.759287221393, 8220.054693710299, 8229.711960081368, 7496.987906632535, 7497.362724168507, 7547.396208303323, 7772.417068719868, 7789.64946577394, 6529.771457998947, 6633.303503132198, 6650.70071846768, 6679.732810687518, 6796.516388358206, 1917.6835822089479, 1926.3161577640017, 1934.7775005874223, 1980.2969945669367, 2006.3278801601627, 746.8804991201382, 841.4488510025943, 850.1625898489982, 1009.1321463541349, 1030.0369154840248, 18920.0, 20283.0, 20328.288509902377, 20363.389098327072, 20363.4909127273, 1003.3227571389283, 1032.9431881246453, 1037.6835065305154, 1054.2040759226734, 1078.029773204107, 10415.479226038668, 10885.564577416973, 11297.47214568109, 11554.59558915434, 11561.190716649753, 443611.0, 447300.0, 449919.0, 452839.0, 453667.0, 95485.0, 95494.52252738691, 96753.51623241979, 97500.51249743706, 102039.489802552, 1018.6078359831519, 1018.8013337399284, 1037.699067270483, 1041.8541404203418, 1071.6195750508614, 17204.397846075328, 17250.65498690044, 18118.0, 18319.35882244115, 18571.44285671432, 40309.798451007555, 41739.0, 43033.354499681904, 43566.56433435752, 45690.543094569955, 56843.0, 57471.0, 57663.423365765804, 57867.71066144642, 59931.0, 8623.0, 8722.476651400682, 8813.515256660945, 8825.558722063999, 8896.39864824312, 11996.76006479883, 13082.803757943448, 13157.407916643966, 13320.134191277535, 13723.656908577366, 11280.153988450735, 11374.146938979775, 11516.769664606829, 11718.472668730099, 11850.585229516926, 11645.534178632759, 11816.881831181578, 11847.930989881219, 11897.583584574499, 12169.452374643339, 11758.470868810753, 11932.164748467523, 12049.759004820029, 12232.388380580758, 12406.689832754253, 8670.956645216733, 8720.476771393736, 8731.65073397057, 8895.332850036144, 9277.536123193679, 9251.121143491358, 11340.0, 11786.81209717402, 12280.508779648713, 12409.131360804657], "value": 9453.56706512618, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.0319079834917, 1446.4214314274288, 1528.7019031288896, 1723.9655206895854, 1745.6683230186263, 8109.229623185803, 8747.91252087479, 8807.823843523125, 9477.336586438953, 9479.383840050392, 8945.418547794388, 9339.533023348822, 9345.205657519102, 9348.953255233731, 9349.158575728183, 2480.293116461813, 2498.8250822442437, 2516.4967006598663, 2517.7859881909967, 2519.6598459207958, 2072.678734796109, 2365.8343915925893, 2378.8096952243777, 2448.338948483908, 2456.5823809952353, 2460.9261722148362, 2484.913028044019, 2485.962710559343, 2489.0914816092086, 2491.489244704831, 2310.803581695558, 2339.7660233976467, 2341.590221711194, 2343.871087090209, 2346.495503466754, 2326.069572171128, 2351.5179388225374, 2371.9644205336936, 2372.833901626887, 2379.2862141357696, 9298.53507324631, 9325.720228393158, 9375.76560585984, 9401.435913845191, 9427.48148851813, 5282.128448805943, 5444.101723215665, 5451.127819548893, 5457.454254574551, 5459.808906688287, 3431.5696562545922, 3504.26410453805, 3570.785752854837, 3591.838367273453, 3614.8011859347725, 1123.3709122891207, 1161.1581603337513, 1184.9881501184964, 1204.0608325506118, 1210.4431961297726, 12885.098043136984, 12887.935560322232, 12890.226586404751, 12891.80662290057, 12900.935495322557, 4418.150293060958, 4731.621470282369, 4762.928556071662, 4781.617470602343, 4784.952150478521, 10862.45687715611, 10869.728256793644, 10884.564617415374, 10885.619003335003, 10897.182711296684, 5476.972615136899, 5481.2874326338115, 5484.506394424521, 5486.79290556085, 5490.286262785812, 4958.727270000185, 5022.648414610942, 5034.244863270465, 5057.064443078042, 5079.949200508022, 4080.5833642318626, 4121.216968775923, 4173.582641735863, 4273.316269396943, 4391.912161756747, 1203.8735932726938, 1271.7329360834144, 1299.7335546213003, 1319.9868001319865, 1326.7943468762357, 924.5515924776478, 929.3328148214532, 940.5673390240567, 944.4427787605346, 968.1431932739436, 6111.297200821962, 6279.309275979638, 6348.8412789679705, 6349.904751428732, 6405.071264666597, 961.8364877970762, 972.9902700973041, 976.4434272464769, 983.3853841349156, 998.4358837256926, 4186.413902053715, 4207.368894665825, 4224.176285624274, 4277.123189746095, 4463.486699029653, 10832.350058996486, 11042.834357484644, 11113.11095112389, 11129.666110016713, 11132.10943124548, 10703.892961070289, 11010.834837477283, 11024.834627480594, 11033.172512061628, 11171.776564468664, 7898.407619428451, 7914.24814642608, 7915.12933577306, 7980.321672657773, 7984.600769961478, 5940.1683764272175, 5959.612625179349, 5979.820605381846, 6061.393860613904, 6130.448259656652, 10093.69718908434, 10097.596096156072, 10114.747131321776, 10122.493875306058, 10260.538275777459, 10634.73413164677, 10815.45922703846, 10846.349219046882, 10966.616168434066, 10970.067544258827, 8103.716369927024, 8223.424360294723, 8271.33829293667, 8283.58582070882, 8349.415540912321, 8975.28197744192, 9050.547472626211, 9057.094290571154, 9061.320400969822, 9066.63733450681, 7722.0, 7737.883931740916, 7760.146383897654, 7988.12130665638, 8329.458585192186, 7502.849943001001, 7517.548947063194, 7703.114141873756, 7850.725224617111, 7918.445708800559, 6882.724691012303, 6938.410235129869, 6939.757108501179, 6945.236024037453, 6953.0, 5190.766415511386, 5215.739213039257, 5253.185756207794, 5272.947270527246, 5368.489993450616, 5174.094533456705, 5209.400918894224, 5224.190250511177, 5370.248165256789, 5387.946120538744], "value": 4785.461432353669, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.54891779214614, 333.49975037443835, 340.3210594863247, 340.465469213335, 341.56450525579896, 5077.822276220333, 5176.7929282828645, 5198.636095473318, 5277.28756617856, 5281.788728450862, 5146.382434107913, 5156.974215128919, 5223.608229382802, 5248.475152484759, 5251.501107394801, 4116.855910043149, 4125.64931980782, 4144.466547377459, 4164.292070348048, 4172.1864236473975, 3999.180168065554, 4125.381192821086, 4150.0, 4161.895952601194, 4174.540800511942, 4086.693497987663, 4097.938530922039, 4101.856435024774, 4102.671786257092, 4123.402106694528, 4079.5716449772644, 4089.773068079579, 4127.0, 4131.855385061538, 4137.81379837905, 3602.6397360264027, 3671.4125739881492, 3675.9448608270895, 3704.240630670706, 3709.4621279914263, 5088.262201980692, 5097.7960881564695, 5102.821401250939, 5105.795768169269, 5111.463296353866, 4750.358701575277, 4759.143354196243, 4768.687186714441, 4781.685036614924, 4789.70677916964, 5393.352797664266, 5448.155535891903, 5480.890382192334, 5509.917351239735, 5529.557635389199, 4054.979725101385, 4057.6550993165615, 4066.755994640331, 4070.6946978976694, 4080.510338759338, 5710.7430165642645, 5738.4835364816945, 5753.741081651336, 5760.36635970043, 5796.159556864273, 4013.9197216055513, 4141.151064031867, 4159.916801664011, 4162.271602469556, 4197.5172855122055, 4198.475190601175, 4224.887556221889, 4227.788610569459, 4228.873133805991, 4239.321708526621, 4746.311784791255, 4757.785899634525, 4780.306855506002, 4781.067691800135, 4784.234522476387, 5392.595555333287, 5507.531859791962, 5560.527355174855, 5598.832035038874, 5615.606907516516, 1362.5776009437106, 1419.7657386531107, 1458.2781523146111, 1475.3066058952274, 1478.6746915678534, 993.8260804359209, 1018.1447584029303, 1018.954147063384, 1038.4236748604446, 1042.4370839746603, 5276.736163191825, 5281.0, 5282.445343238905, 5298.205269209572, 5301.356579460303, 5560.443955604407, 5568.359638641608, 5568.638038527482, 5586.972065139648, 5591.776328946878, 5169.630048037273, 5246.554042906396, 5275.707451674364, 5373.274607927958, 5379.569634429236, 2571.4642366745725, 2610.4126571521256, 2630.1320564213765, 2651.4166883285648, 2653.7080921098664, 5051.419086804991, 5088.77100530477, 5096.146158151447, 5107.8723031923755, 5115.565176959925, 4870.561649451566, 5017.698938063728, 5042.142835717937, 5065.0, 5076.314697515862, 5042.4705405931845, 5073.822416215415, 5078.365204349457, 5110.105731496973, 5111.693298402033, 4862.857228551358, 4937.39534651247, 4949.406071271326, 4950.950490495049, 4965.329680493087, 4620.745858977789, 4858.27125931099, 4882.853514394574, 4898.191798353232, 4907.607391408607, 5045.493382099406, 5053.267276244997, 5057.216131499624, 5076.847694569169, 5082.348236822973, 3809.4857194279525, 3832.6011005983173, 3841.2317536492255, 3857.0550215197454, 3869.1681288523105, 4855.75721213945, 4887.873774907926, 4897.510248975074, 4932.4081110267, 5060.84817455477, 4860.392450943632, 4878.756062196947, 4924.310596516419, 4935.506449355035, 4947.233178857283, 4828.734419606815, 4918.655694101379, 4923.679960802537, 4938.123513065012, 4957.413627639121, 4759.476457589593, 4896.436909755281, 4922.852314430573, 4934.259861020875, 4946.353700770071, 4739.952600473951, 4855.843701593993, 4857.275667138115, 4930.211166213388, 4936.407631084293, 4739.312799644102, 4773.260144677581, 4797.928031079467, 4810.759462026955, 4885.389326334208], "value": 4040.6124494693013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.8543633991374, 759.3469616130127, 792.6155814430002, 800.7877912353231, 839.680921249925, 15048.699026019473, 15600.531984040468, 15603.531894043168, 15651.21743912804, 15655.686886262281, 15307.234638268097, 15445.22773861308, 15466.0, 15477.690446191098, 15520.379184832622, 656.3469347998724, 784.8273379856422, 794.7258195922412, 814.8818421328933, 817.6606708216083, 789.6328207383566, 791.4815795653856, 793.1275596843454, 796.8167321516055, 797.2585495489193, 844.5270648436864, 845.3026253340987, 845.6490556419079, 845.8477474054667, 846.71635002274, 687.5118665747325, 814.775936617432, 830.2278880641002, 835.5362773660615, 839.8824164616958, 614.9938500615026, 615.6460035479572, 616.1097214525013, 617.0435824472095, 620.4354037825602, 15296.541103766906, 15312.617184570363, 15353.15557644329, 15369.0, 15378.692426151414, 13900.652483687887, 14187.0, 14236.857631423656, 14293.428262869473, 14430.0, 11688.707782305426, 11818.881811181951, 12076.637700868989, 12195.573154939624, 12504.249745015237, 4460.955390446086, 4487.326900964848, 4634.721916684976, 4644.210484217691, 4677.462091859412, 74271.99999999946, 74863.0, 77020.61489692572, 78809.14885639295, 79032.0, 11455.198136130557, 11459.713507162222, 11625.973767390858, 11680.123990700731, 11759.0, 21173.15307387718, 21197.152113915272, 21198.15207391718, 21199.152033918777, 21234.0, 8158.877616835753, 8397.832043359098, 8399.580020998925, 8417.98984121897, 8581.442206256574, 4677.342635167296, 4699.083678682624, 4778.474367819537, 4898.314236006961, 4927.975360123248, 3223.9677603223668, 3309.12308238317, 3349.112485191426, 3358.6809253120914, 3361.680640339213, 522.2401405954328, 592.831043152708, 600.3996003996004, 604.6432604763123, 605.0410099991562, 737.4395459450856, 737.4763917618444, 750.7034721285062, 770.7456539342004, 777.1257335497565, 9763.218942484584, 9837.884854756261, 10373.27387082912, 10598.841017384746, 10654.307470014424, 649.7011374767566, 665.3114026982147, 683.7948615415355, 711.2425267090532, 728.4682182007165, 4639.188142075123, 4842.346283251716, 4987.725675087905, 5040.697558146523, 5068.795074142759, 102922.48538757258, 126719.36640316923, 126737.0, 127249.0, 130362.0, 61556.0, 92053.0, 104220.47889760503, 107423.81219004921, 107818.0, 75731.0, 76717.0, 78620.60689696515, 84360.57819710863, 85451.57274213589, 9437.480938548171, 9729.011105446732, 10019.499025048864, 10050.145737612092, 10061.748456288651, 53144.4685553155, 53228.4677153239, 53836.0, 54924.725376372866, 55915.0, 72361.0, 75687.62156189184, 76564.0, 76817.23182768101, 78544.0, 11535.76928461443, 11687.474063667325, 12592.0, 12708.682282943, 13287.734245314847, 13633.454661813417, 13827.585172444842, 13881.0, 13988.650283742987, 14631.634209144857, 13801.585952421041, 14069.507567235098, 14401.0, 15763.448279310169, 15784.052956822168, 13480.730385392433, 13737.450501979809, 13895.37470813836, 14462.05996610217, 14656.706865862836, 13399.665008374868, 13736.862631373559, 14414.207218603078, 14417.351219194952, 14616.0, 11191.216614837209, 11363.886361136283, 12031.939840301093, 12032.518699252281, 12194.939025304817, 11807.704807379885, 11947.761044778881, 12129.0, 12312.13815032939, 12455.501779928702], "value": 7641.070190756172, "unit": "score"}], "name": "dromaeo_css", "value": 7262.258907715721, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 127.0, 112.0, 113.0, 113.0, 112.0, 112.0, 110.0, 110.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 161.0, 179.0, 124.0, 143.0, 149.0, 120.0, 183.0, 119.0], "value": 145.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 164.0, 162.0, 163.0, 162.0, 161.0, 160.0, 161.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 177.0, 76.0, 76.0, 76.0, 75.0, 170.0, 76.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 144.0, 82.0, 82.0, 81.0, 145.0, 83.0, 83.0, 141.0], "value": 100.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 53.0, 53.0, 52.0, 53.0, 54.0, 54.0, 50.0, 50.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 79.0, 79.0, 79.0, 82.0, 83.0, 85.0, 81.0, 79.0, 88.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 127.0, 128.0, 142.0, 125.0, 133.0, 126.0, 125.0, 157.0, 125.0], "value": 132.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 169.0, 168.0, 169.0, 164.0, 163.0, 163.0, 163.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 65.0, 64.0, 65.0, 63.0, 64.0, 63.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1533.6000000000001, "alertThreshold": 2.0}]} 01:44:58 INFO - Return code: 0 01:44:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:44:58 INFO - # TBPL SUCCESS # 01:44:58 INFO - Running post-action listener: _resource_record_post_action 01:44:58 INFO - [mozharness: 2016-08-26 08:44:58.341374Z] Finished run-tests step (success) 01:44:58 INFO - Running post-run listener: _resource_record_post_run 01:44:58 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 0; Write bytes: 418004992; Read time: 0; Write time: 358608 01:44:58 INFO - TinderboxPrint: CPU usage
13.1% 01:44:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 01:44:58 INFO - TinderboxPrint: I/O write bytes / time
418,004,992 / 358,608 01:44:58 INFO - TinderboxPrint: CPU idle
8,282.9 (86.9%) 01:44:58 INFO - TinderboxPrint: CPU user
1,199.3 (12.6%) 01:44:58 INFO - TinderboxPrint: Swap in / out
0 / 0 01:44:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 128782336; Read time: 0; Write time: 99120 01:44:58 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288337920; Read time: 0; Write time: 259472 01:44:58 INFO - Running post-run listener: _upload_blobber_files 01:44:58 INFO - Blob upload gear active. 01:44:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:44:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:44:59 INFO - (blobuploader) - INFO - Open directory for files ... 01:44:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:44:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:44:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:45:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:45:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:45:00 INFO - (blobuploader) - INFO - Done attempting. 01:45:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:45:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:45:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:45:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:45:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:45:01 INFO - (blobuploader) - INFO - Done attempting. 01:45:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:45:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:45:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:45:07 INFO - (blobuploader) - INFO - Done attempting. 01:45:07 INFO - (blobuploader) - INFO - Iteration through files over. 01:45:07 INFO - Return code: 0 01:45:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:45:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:45:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e956e012a13b614d371adae7437f1e562112c6b97e8b3c62dedafe17d95fe326f987802c2cb3e3b6c2a1bbe9a2d91fcf0ad4aa5e77b6fe4dd9df063d39cd9231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2344d2ad273de393fefcdde77ed8e1ceeea5dcdd80a0ce55e2ce17cbea0aabb70dfbb9fe4cdf87e058a46047e7b4091020223c13adf9f81db7ee25dae01a95d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f57f44c38ddffcae0361ea6d947db5897293ef7334dae2f3b4e69a49e3f06b3de2b389a57fbc8b9ff7436ee08ef59287d98a2ed5e332ae4419833a450885124b"} 01:45:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:45:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:45:07 INFO - Contents: 01:45:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e956e012a13b614d371adae7437f1e562112c6b97e8b3c62dedafe17d95fe326f987802c2cb3e3b6c2a1bbe9a2d91fcf0ad4aa5e77b6fe4dd9df063d39cd9231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2344d2ad273de393fefcdde77ed8e1ceeea5dcdd80a0ce55e2ce17cbea0aabb70dfbb9fe4cdf87e058a46047e7b4091020223c13adf9f81db7ee25dae01a95d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f57f44c38ddffcae0361ea6d947db5897293ef7334dae2f3b4e69a49e3f06b3de2b389a57fbc8b9ff7436ee08ef59287d98a2ed5e332ae4419833a450885124b"} 01:45:07 INFO - Running post-run listener: copy_logs_to_upload_dir 01:45:07 INFO - Copying logs to upload dir... 01:45:07 INFO - mkdir: /builds/slave/test/build/upload/logs 01:45:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.156849 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-08-26 01:45:07.142113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 01:45:07.145719) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e956e012a13b614d371adae7437f1e562112c6b97e8b3c62dedafe17d95fe326f987802c2cb3e3b6c2a1bbe9a2d91fcf0ad4aa5e77b6fe4dd9df063d39cd9231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2344d2ad273de393fefcdde77ed8e1ceeea5dcdd80a0ce55e2ce17cbea0aabb70dfbb9fe4cdf87e058a46047e7b4091020223c13adf9f81db7ee25dae01a95d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f57f44c38ddffcae0361ea6d947db5897293ef7334dae2f3b4e69a49e3f06b3de2b389a57fbc8b9ff7436ee08ef59287d98a2ed5e332ae4419833a450885124b"} build_url:https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/QFauMh6JQ8eAKOZeTqCgEg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e956e012a13b614d371adae7437f1e562112c6b97e8b3c62dedafe17d95fe326f987802c2cb3e3b6c2a1bbe9a2d91fcf0ad4aa5e77b6fe4dd9df063d39cd9231", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2344d2ad273de393fefcdde77ed8e1ceeea5dcdd80a0ce55e2ce17cbea0aabb70dfbb9fe4cdf87e058a46047e7b4091020223c13adf9f81db7ee25dae01a95d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f57f44c38ddffcae0361ea6d947db5897293ef7334dae2f3b4e69a49e3f06b3de2b389a57fbc8b9ff7436ee08ef59287d98a2ed5e332ae4419833a450885124b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 01:45:07.171657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:45:07.171978) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1472198747.644203-2081101618 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004403 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 01:45:07.256912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 01:45:07.257243) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 01:45:07.257528) ========= ========= Total master_lag: 0.19 =========