builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1467942247.9 results: success (0) buildid: 20160707172726 builduid: 19a713d0a57246398ef269edd187de7f revision: 23dc78b7b57e9f91798ea44c242a04e112c37db0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:07.899727) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:07.900143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-07-07 18:44:07.900422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-07-07 18:44:14.055565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:14.055964) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-07 18:44:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-07-07 18:44:14 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123077 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:14.228435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:14.231311) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:14.281850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 18:44:14.282212) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23dc78b7b57e9f91798ea44c242a04e112c37db0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 23dc78b7b57e9f91798ea44c242a04e112c37db0 --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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-07 18:44:14,663 truncating revision to first 12 chars 2016-07-07 18:44:14,663 Setting DEBUG logging. 2016-07-07 18:44:14,663 attempt 1/10 2016-07-07 18:44:14,663 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23dc78b7b57e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 18:44:15,292 unpacking tar archive at: mozilla-central-23dc78b7b57e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.995844 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 18:44:15.300186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:15.304257) ========= script_repo_revision: 23dc78b7b57e9f91798ea44c242a04e112c37db0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:15.305117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:15.305663) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 18:44:15.321920) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-07 18:44:15.322612) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:44:15 INFO - MultiFileLogger online at 20160707 18:44:15 in /builds/slave/test 18:44:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:44:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:44:15 INFO - {'append_to_log': False, 18:44:15 INFO - 'base_work_dir': '/builds/slave/test', 18:44:15 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:44:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:44:15 INFO - 'branch': 'Firefox-Non-PGO', 18:44:15 INFO - 'buildbot_json_path': 'buildprops.json', 18:44:15 INFO - 'config_files': ('talos/linux_config.py',), 18:44:15 INFO - 'default_actions': ('clobber', 18:44:15 INFO - 'read-buildbot-config', 18:44:15 INFO - 'download-and-extract', 18:44:15 INFO - 'populate-webroot', 18:44:15 INFO - 'create-virtualenv', 18:44:15 INFO - 'install', 18:44:15 INFO - 'run-tests'), 18:44:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:44:15 INFO - 'download_minidump_stackwalk': True, 18:44:15 INFO - 'download_symbols': 'ondemand', 18:44:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:44:15 INFO - 'tooltool.py': '/tools/tooltool.py', 18:44:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:44:15 INFO - '/tools/misc-python/virtualenv.py')}, 18:44:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:44:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:44:15 INFO - 'installer_path': 'installer.exe', 18:44:15 INFO - 'log_level': 'info', 18:44:15 INFO - 'log_name': 'talos', 18:44:15 INFO - 'log_to_console': True, 18:44:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:44:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:44:15 INFO - 'opt_config_files': (), 18:44:15 INFO - 'pip_index': False, 18:44:15 INFO - 'sps_profile': False, 18:44:15 INFO - 'sps_profile_interval': 0, 18:44:15 INFO - 'suite': 'dromaeojs', 18:44:15 INFO - 'system_bits': '32', 18:44:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:44:15 INFO - 'title': 'talos-linux64-ix-006', 18:44:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:44:15 INFO - 'use_talos_json': True, 18:44:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:44:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:44:15 INFO - 'work_dir': 'build'} 18:44:15 INFO - ##### 18:44:15 INFO - ##### Running clobber step. 18:44:15 INFO - ##### 18:44:15 INFO - Running pre-action listener: _resource_record_pre_action 18:44:15 INFO - Running main action method: clobber 18:44:15 INFO - rmtree: /builds/slave/test/build 18:44:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:44:16 INFO - Running post-action listener: _resource_record_post_action 18:44:16 INFO - ##### 18:44:16 INFO - ##### Finished clobber step (success) 18:44:16 INFO - ##### 18:44:16 INFO - ##### 18:44:16 INFO - ##### Running read-buildbot-config step. 18:44:16 INFO - ##### 18:44:16 INFO - Running pre-action listener: _resource_record_pre_action 18:44:16 INFO - Running main action method: read_buildbot_config 18:44:16 INFO - Using buildbot properties: 18:44:16 INFO - { 18:44:16 INFO - "project": "", 18:44:16 INFO - "product": "firefox", 18:44:16 INFO - "script_repo_revision": "production", 18:44:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:44:16 INFO - "repository": "", 18:44:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:44:16 INFO - "buildid": "20160707172726", 18:44:16 INFO - "pgo_build": "False", 18:44:16 INFO - "basedir": "/builds/slave/test", 18:44:16 INFO - "buildnumber": 139, 18:44:16 INFO - "slavename": "talos-linux64-ix-006", 18:44:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:44:16 INFO - "platform": "ubuntu64_hw", 18:44:16 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:44:16 INFO - "branch": "mozilla-central", 18:44:16 INFO - "repo_path": "mozilla-central", 18:44:16 INFO - "revision": "23dc78b7b57e9f91798ea44c242a04e112c37db0", 18:44:16 INFO - "stage_platform": "linux64", 18:44:16 INFO - "builduid": "19a713d0a57246398ef269edd187de7f", 18:44:16 INFO - "slavebuilddir": "test" 18:44:16 INFO - } 18:44:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:44:16 INFO - Running post-action listener: _resource_record_post_action 18:44:16 INFO - ##### 18:44:16 INFO - ##### Finished read-buildbot-config step (success) 18:44:16 INFO - ##### 18:44:16 INFO - ##### 18:44:16 INFO - ##### Running download-and-extract step. 18:44:16 INFO - ##### 18:44:16 INFO - Running pre-action listener: _resource_record_pre_action 18:44:16 INFO - Running main action method: download_and_extract 18:44:16 INFO - mkdir: /builds/slave/test/build/tests 18:44:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:16 INFO - https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:44:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:44:19 INFO - Downloaded 1361 bytes. 18:44:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:44:19 INFO - Using the following test package requirements: 18:44:19 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:44:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:44:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:44:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'jsshell-linux-x86_64.zip'], 18:44:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:44:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:44:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:44:19 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:44:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:44:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:44:19 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:44:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:44:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:19 INFO - https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:44:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:44:20 INFO - Downloaded 22734692 bytes. 18:44:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:44:22 INFO - Return code: 0 18:44:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:44:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:22 INFO - https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:44:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:44:23 INFO - Downloaded 22734692 bytes. 18:44:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:44:25 INFO - Return code: 0 18:44:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:25 INFO - https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:44:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:44:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:44:26 INFO - Downloaded 14403333 bytes. 18:44:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:44:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:44:26 INFO - Return code: 0 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:26 INFO - https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:44:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:44:32 INFO - Downloaded 56646878 bytes. 18:44:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:32 INFO - mkdir: /builds/slave/test/properties 18:44:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:44:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:44:32 INFO - Contents: 18:44:32 INFO - build_url:https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:44:33 INFO - Running post-action listener: _resource_record_post_action 18:44:33 INFO - Running post-action listener: set_extra_try_arguments 18:44:33 INFO - ##### 18:44:33 INFO - ##### Finished download-and-extract step (success) 18:44:33 INFO - ##### 18:44:33 INFO - ##### 18:44:33 INFO - ##### Running populate-webroot step. 18:44:33 INFO - ##### 18:44:33 INFO - Running pre-action listener: _resource_record_pre_action 18:44:33 INFO - Running main action method: populate_webroot 18:44:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:44:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:44:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:44:33 INFO - '--tppagecycles', 18:44:33 INFO - '7'], 18:44:33 INFO - 'tests': ['tsvgm']}}, 18:44:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:44:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:44:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:44:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:44:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:44:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['damp', 'tps']}, 18:44:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'tests': ['damp', 'tps']}, 18:44:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['dromaeo_dom']}, 18:44:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:44:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['basic_compositor_video']}, 18:44:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:44:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'other-e10s': {'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['a11yr', 18:44:33 INFO - 'ts_paint', 18:44:33 INFO - 'tpaint', 18:44:33 INFO - 'sessionrestore', 18:44:33 INFO - 'sessionrestore_no_auto_restore', 18:44:33 INFO - 'tabpaint']}, 18:44:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['tsvgx', 18:44:33 INFO - 'tsvgr_opacity', 18:44:33 INFO - 'tart', 18:44:33 INFO - 'tscrollx', 18:44:33 INFO - 'cart']}, 18:44:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:44:33 INFO - 'tsvgr_opacity', 18:44:33 INFO - 'tart', 18:44:33 INFO - 'tscrollx', 18:44:33 INFO - 'cart']}, 18:44:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'talos_options': ['--disable-e10s'], 18:44:33 INFO - 'tests': ['tp5o']}, 18:44:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'tests': ['tp5o']}, 18:44:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'talos_options': ['--disable-e10s', 18:44:33 INFO - '--xperf_path', 18:44:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:33 INFO - 'tests': ['tp5n']}, 18:44:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:44:33 INFO - 'talos_options': ['--xperf_path', 18:44:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:44:33 INFO - 'tests': ['tp5n']}}, 18:44:33 INFO - 'talos.zip': {'path': '', 18:44:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:44:33 INFO - Running post-action listener: _resource_record_post_action 18:44:33 INFO - ##### 18:44:33 INFO - ##### Finished populate-webroot step (success) 18:44:33 INFO - ##### 18:44:33 INFO - ##### 18:44:33 INFO - ##### Running create-virtualenv step. 18:44:33 INFO - ##### 18:44:33 INFO - Running pre-action listener: _resource_record_pre_action 18:44:33 INFO - Running main action method: create_virtualenv 18:44:33 INFO - Creating virtualenv /builds/slave/test/build/venv 18:44:33 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 18:44:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:44:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:44:33 INFO - Using real prefix '/usr' 18:44:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:44:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:44:35 INFO - Installing pip.................done. 18:44:35 INFO - Return code: 0 18:44:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:44:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:35 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:35 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 18:44:35 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 18:44:35 INFO - Using env: {'DISPLAY': ':0', 18:44:35 INFO - 'HOME': '/home/cltbld', 18:44:35 INFO - 'LANG': 'en_US.UTF-8', 18:44:35 INFO - 'LANGUAGE': 'en_US:en', 18:44:35 INFO - 'LOGNAME': 'cltbld', 18:44:35 INFO - 'MAIL': '/var/mail/cltbld', 18:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:35 INFO - 'MOZ_NO_REMOTE': '1', 18:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:35 INFO - 'NO_EM_RESTART': '1', 18:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:35 INFO - 'PWD': '/builds/slave/test', 18:44:35 INFO - 'SHELL': '/bin/bash', 18:44:35 INFO - 'SHLVL': '1', 18:44:35 INFO - 'TERM': 'linux', 18:44:35 INFO - 'TMOUT': '86400', 18:44:35 INFO - 'USER': 'cltbld', 18:44:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:35 INFO - '_': '/tools/buildbot/bin/python'} 18:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:35 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 18:44:35 INFO - Cleaning up... 18:44:35 INFO - Return code: 0 18:44:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:44:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:35 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:35 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 18:44:35 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 18:44:35 INFO - Using env: {'DISPLAY': ':0', 18:44:35 INFO - 'HOME': '/home/cltbld', 18:44:35 INFO - 'LANG': 'en_US.UTF-8', 18:44:35 INFO - 'LANGUAGE': 'en_US:en', 18:44:35 INFO - 'LOGNAME': 'cltbld', 18:44:35 INFO - 'MAIL': '/var/mail/cltbld', 18:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:35 INFO - 'MOZ_NO_REMOTE': '1', 18:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:35 INFO - 'NO_EM_RESTART': '1', 18:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:35 INFO - 'PWD': '/builds/slave/test', 18:44:35 INFO - 'SHELL': '/bin/bash', 18:44:35 INFO - 'SHLVL': '1', 18:44:35 INFO - 'TERM': 'linux', 18:44:35 INFO - 'TMOUT': '86400', 18:44:35 INFO - 'USER': 'cltbld', 18:44:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:35 INFO - '_': '/tools/buildbot/bin/python'} 18:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:36 INFO - Downloading/unpacking psutil>=3.1.1 18:44:36 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 18:44:36 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 18:44:36 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 18:44:36 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 18:44:36 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 18:44:36 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 18:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:44:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:38 INFO - Installing collected packages: psutil 18:44:38 INFO - Running setup.py install for psutil 18:44:38 INFO - building 'psutil._psutil_linux' extension 18:44:38 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 18:44:38 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 18:44:38 INFO - building 'psutil._psutil_posix' extension 18:44:38 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 18:44:38 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 18:44:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:44:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:44:38 INFO - Successfully installed psutil 18:44:38 INFO - Cleaning up... 18:44:38 INFO - Return code: 0 18:44:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:38 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:38 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 18:44:38 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 18:44:38 INFO - Using env: {'DISPLAY': ':0', 18:44:38 INFO - 'HOME': '/home/cltbld', 18:44:38 INFO - 'LANG': 'en_US.UTF-8', 18:44:38 INFO - 'LANGUAGE': 'en_US:en', 18:44:38 INFO - 'LOGNAME': 'cltbld', 18:44:38 INFO - 'MAIL': '/var/mail/cltbld', 18:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:38 INFO - 'MOZ_NO_REMOTE': '1', 18:44:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:38 INFO - 'NO_EM_RESTART': '1', 18:44:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:38 INFO - 'PWD': '/builds/slave/test', 18:44:38 INFO - 'SHELL': '/bin/bash', 18:44:38 INFO - 'SHLVL': '1', 18:44:38 INFO - 'TERM': 'linux', 18:44:38 INFO - 'TMOUT': '86400', 18:44:38 INFO - 'USER': 'cltbld', 18:44:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:38 INFO - '_': '/tools/buildbot/bin/python'} 18:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:44:38 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 18:44:38 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 18:44:38 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 18:44:38 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 18:44:38 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 18:44:38 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 18:44:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:44:40 INFO - Installing collected packages: mozsystemmonitor 18:44:40 INFO - Running setup.py install for mozsystemmonitor 18:44:40 INFO - Successfully installed mozsystemmonitor 18:44:40 INFO - Cleaning up... 18:44:41 INFO - Return code: 0 18:44:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:44:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:41 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:41 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:41 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 18:44:41 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 18:44:41 INFO - Using env: {'DISPLAY': ':0', 18:44:41 INFO - 'HOME': '/home/cltbld', 18:44:41 INFO - 'LANG': 'en_US.UTF-8', 18:44:41 INFO - 'LANGUAGE': 'en_US:en', 18:44:41 INFO - 'LOGNAME': 'cltbld', 18:44:41 INFO - 'MAIL': '/var/mail/cltbld', 18:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:41 INFO - 'MOZ_NO_REMOTE': '1', 18:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:41 INFO - 'NO_EM_RESTART': '1', 18:44:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:41 INFO - 'PWD': '/builds/slave/test', 18:44:41 INFO - 'SHELL': '/bin/bash', 18:44:41 INFO - 'SHLVL': '1', 18:44:41 INFO - 'TERM': 'linux', 18:44:41 INFO - 'TMOUT': '86400', 18:44:41 INFO - 'USER': 'cltbld', 18:44:41 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:41 INFO - '_': '/tools/buildbot/bin/python'} 18:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:41 INFO - Downloading/unpacking jsonschema==2.5.1 18:44:41 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 18:44:41 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 18:44:41 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 18:44:41 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 18:44:41 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 18:44:41 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 18:44:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:44:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 INFO - Downloading functools32-3.2.3-2.tar.gz 18:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:44:43 INFO - warning: no files found matching '*.txt' 18:44:43 INFO - no previously-included directories found matching 'build' 18:44:43 INFO - no previously-included directories found matching 'dist' 18:44:43 INFO - no previously-included directories found matching '.git*' 18:44:43 INFO - Installing collected packages: jsonschema, functools32 18:44:43 INFO - Running setup.py install for functools32 18:44:43 INFO - warning: no files found matching '*.txt' 18:44:43 INFO - no previously-included directories found matching 'build' 18:44:43 INFO - no previously-included directories found matching 'dist' 18:44:43 INFO - no previously-included directories found matching '.git*' 18:44:43 INFO - Successfully installed jsonschema functools32 18:44:43 INFO - Cleaning up... 18:44:43 INFO - Return code: 0 18:44:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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', '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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:44: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 blobuploader==1.2.4 18:44:43 INFO - Using env: {'DISPLAY': ':0', 18:44:43 INFO - 'HOME': '/home/cltbld', 18:44:43 INFO - 'LANG': 'en_US.UTF-8', 18:44:43 INFO - 'LANGUAGE': 'en_US:en', 18:44:43 INFO - 'LOGNAME': 'cltbld', 18:44:43 INFO - 'MAIL': '/var/mail/cltbld', 18:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:43 INFO - 'MOZ_NO_REMOTE': '1', 18:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:43 INFO - 'NO_EM_RESTART': '1', 18:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:43 INFO - 'PWD': '/builds/slave/test', 18:44:43 INFO - 'SHELL': '/bin/bash', 18:44:43 INFO - 'SHLVL': '1', 18:44:43 INFO - 'TERM': 'linux', 18:44:43 INFO - 'TMOUT': '86400', 18:44:43 INFO - 'USER': 'cltbld', 18:44:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:43 INFO - '_': '/tools/buildbot/bin/python'} 18:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:43 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 18:44:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:44:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:44:45 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 18:44:45 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 18:44:45 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 18:44:45 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 18:44:45 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 18:44:45 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 18:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:44:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:44:46 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 18:44:46 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 18:44:46 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 18:44:46 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 18:44:46 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 18:44:46 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 18:44:46 INFO - Downloading docopt-0.6.1.tar.gz 18:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:44:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:44:46 INFO - Running setup.py install for blobuploader 18:44:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:44:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:44:46 INFO - Running setup.py install for requests 18:44:46 INFO - Running setup.py install for docopt 18:44:47 INFO - Successfully installed blobuploader requests docopt 18:44:47 INFO - Cleaning up... 18:44:47 INFO - Return code: 0 18:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:47 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:47 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:47 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 18:44:47 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 18:44:47 INFO - Using env: {'DISPLAY': ':0', 18:44:47 INFO - 'HOME': '/home/cltbld', 18:44:47 INFO - 'LANG': 'en_US.UTF-8', 18:44:47 INFO - 'LANGUAGE': 'en_US:en', 18:44:47 INFO - 'LOGNAME': 'cltbld', 18:44:47 INFO - 'MAIL': '/var/mail/cltbld', 18:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:47 INFO - 'MOZ_NO_REMOTE': '1', 18:44:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:47 INFO - 'NO_EM_RESTART': '1', 18:44:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:47 INFO - 'PWD': '/builds/slave/test', 18:44:47 INFO - 'SHELL': '/bin/bash', 18:44:47 INFO - 'SHLVL': '1', 18:44:47 INFO - 'TERM': 'linux', 18:44:47 INFO - 'TMOUT': '86400', 18:44:47 INFO - 'USER': 'cltbld', 18:44:47 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:47 INFO - '_': '/tools/buildbot/bin/python'} 18:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:47 INFO - Running setup.py (path:/tmp/pip-rmvesf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:47 INFO - Running setup.py (path:/tmp/pip-83r7Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:47 INFO - Running setup.py (path:/tmp/pip-VHnWxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:47 INFO - Running setup.py (path:/tmp/pip-MPzkn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:47 INFO - Running setup.py (path:/tmp/pip-_IXvaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:47 INFO - Running setup.py (path:/tmp/pip-GtOAnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:47 INFO - Running setup.py (path:/tmp/pip-5stitw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:47 INFO - Running setup.py (path:/tmp/pip-k3nfM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:47 INFO - Running setup.py (path:/tmp/pip-67bjg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:47 INFO - Running setup.py (path:/tmp/pip-c6alCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:47 INFO - Running setup.py (path:/tmp/pip-xDgwsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:48 INFO - Running setup.py (path:/tmp/pip-35Tsry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:48 INFO - Running setup.py (path:/tmp/pip-CfYLrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:48 INFO - Running setup.py (path:/tmp/pip-nO77N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:48 INFO - Running setup.py (path:/tmp/pip-0oMCfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:48 INFO - Running setup.py (path:/tmp/pip-Gs7CAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:48 INFO - Running setup.py (path:/tmp/pip-qsrebI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:44:48 INFO - Running setup.py install for manifestparser 18:44:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:44:48 INFO - Running setup.py install for mozcrash 18:44:48 INFO - Running setup.py install for mozdebug 18:44:48 INFO - Running setup.py install for mozdevice 18:44:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:44:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:44:48 INFO - Running setup.py install for mozfile 18:44:49 INFO - Running setup.py install for mozhttpd 18:44:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozinfo 18:44:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozInstall 18:44:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozleak 18:44:49 INFO - Running setup.py install for mozlog 18:44:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for moznetwork 18:44:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozprocess 18:44:49 INFO - Running setup.py install for mozprofile 18:44:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozrunner 18:44:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:44:49 INFO - Running setup.py install for mozscreenshot 18:44:50 INFO - Running setup.py install for moztest 18:44:50 INFO - Running setup.py install for mozversion 18:44:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:44:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:44:50 INFO - Cleaning up... 18:44:50 INFO - Return code: 0 18:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:50 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:50 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 18:44:50 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 18:44:50 INFO - Using env: {'DISPLAY': ':0', 18:44:50 INFO - 'HOME': '/home/cltbld', 18:44:50 INFO - 'LANG': 'en_US.UTF-8', 18:44:50 INFO - 'LANGUAGE': 'en_US:en', 18:44:50 INFO - 'LOGNAME': 'cltbld', 18:44:50 INFO - 'MAIL': '/var/mail/cltbld', 18:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:50 INFO - 'MOZ_NO_REMOTE': '1', 18:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:50 INFO - 'NO_EM_RESTART': '1', 18:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:50 INFO - 'PWD': '/builds/slave/test', 18:44:50 INFO - 'SHELL': '/bin/bash', 18:44:50 INFO - 'SHLVL': '1', 18:44:50 INFO - 'TERM': 'linux', 18:44:50 INFO - 'TMOUT': '86400', 18:44:50 INFO - 'USER': 'cltbld', 18:44:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:50 INFO - '_': '/tools/buildbot/bin/python'} 18:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:44:50 INFO - Running setup.py (path:/tmp/pip-NQ1rTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:44:50 INFO - Running setup.py (path:/tmp/pip-ygGkD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:44:50 INFO - Running setup.py (path:/tmp/pip-aRPuHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:44:50 INFO - Running setup.py (path:/tmp/pip-LpYW9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:44:50 INFO - Running setup.py (path:/tmp/pip-gbYmn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:44:50 INFO - Running setup.py (path:/tmp/pip-ZS9_1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:44:50 INFO - Running setup.py (path:/tmp/pip-NBOR_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:44:50 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)) 18:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:44:51 INFO - Running setup.py (path:/tmp/pip-c1I50R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:44:51 INFO - Running setup.py (path:/tmp/pip-yLRWvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:44:51 INFO - Running setup.py (path:/tmp/pip-P7CMq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:44:51 INFO - Running setup.py (path:/tmp/pip-ZtnsIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:44:51 INFO - Running setup.py (path:/tmp/pip-_dkL6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:44:51 INFO - Running setup.py (path:/tmp/pip-tb1Cwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:44:51 INFO - Running setup.py (path:/tmp/pip-sQlE2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:51 INFO - Running setup.py (path:/tmp/pip-Gn2uYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:44:51 INFO - Running setup.py (path:/tmp/pip-pmD9PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:44:51 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)) 18:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:44:51 INFO - Running setup.py (path:/tmp/pip-tXOW9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:44:51 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)) 18:44:51 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)) 18:44:51 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)) 18:44:51 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)) 18:44:51 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)) 18:44:51 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)) 18:44:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:44: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 18:44: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 18:44: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 18:44: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 18:44: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 18:44: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 18:44:54 INFO - Downloading blessings-1.6.tar.gz 18:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:44:54 INFO - Installing collected packages: blessings 18:44:54 INFO - Running setup.py install for blessings 18:44:54 INFO - Successfully installed blessings 18:44:54 INFO - Cleaning up... 18:44:54 INFO - Return code: 0 18:44:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:55 INFO - Reading from file tmpfile_stdout 18:44:55 INFO - Current package versions: 18:44:55 INFO - argparse == 1.2.1 18:44:55 INFO - blessings == 1.6 18:44:55 INFO - blobuploader == 1.2.4 18:44:55 INFO - docopt == 0.6.1 18:44:55 INFO - functools32 == 3.2.3-2 18:44:55 INFO - jsonschema == 2.5.1 18:44:55 INFO - manifestparser == 1.1 18:44:55 INFO - mozInstall == 1.12 18:44:55 INFO - mozcrash == 0.17 18:44:55 INFO - mozdebug == 0.1 18:44:55 INFO - mozdevice == 0.48 18:44:55 INFO - mozfile == 1.2 18:44:55 INFO - mozhttpd == 0.7 18:44:55 INFO - mozinfo == 0.9 18:44:55 INFO - mozleak == 0.1 18:44:55 INFO - mozlog == 3.2 18:44:55 INFO - moznetwork == 0.27 18:44:55 INFO - mozprocess == 0.23 18:44:55 INFO - mozprofile == 0.28 18:44:55 INFO - mozrunner == 6.12 18:44:55 INFO - mozscreenshot == 0.1 18:44:55 INFO - mozsystemmonitor == 0.3 18:44:55 INFO - moztest == 0.7 18:44:55 INFO - mozversion == 1.4 18:44:55 INFO - psutil == 3.1.1 18:44:55 INFO - requests == 1.2.3 18:44:55 INFO - wsgiref == 0.1.2 18:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:55 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:55 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 18:44:55 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 18:44:55 INFO - Using env: {'DISPLAY': ':0', 18:44:55 INFO - 'HOME': '/home/cltbld', 18:44:55 INFO - 'LANG': 'en_US.UTF-8', 18:44:55 INFO - 'LANGUAGE': 'en_US:en', 18:44:55 INFO - 'LOGNAME': 'cltbld', 18:44:55 INFO - 'MAIL': '/var/mail/cltbld', 18:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:55 INFO - 'MOZ_NO_REMOTE': '1', 18:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:55 INFO - 'NO_EM_RESTART': '1', 18:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:55 INFO - 'PWD': '/builds/slave/test', 18:44:55 INFO - 'SHELL': '/bin/bash', 18:44:55 INFO - 'SHLVL': '1', 18:44:55 INFO - 'TERM': 'linux', 18:44:55 INFO - 'TMOUT': '86400', 18:44:55 INFO - 'USER': 'cltbld', 18:44:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:55 INFO - '_': '/tools/buildbot/bin/python'} 18:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 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)) 18:44:55 INFO - Cleaning up... 18:44:55 INFO - Return code: 0 18:44:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:44:55 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 0x248e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', '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 18:44:55 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 18:44:55 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 18:44:55 INFO - Using env: {'DISPLAY': ':0', 18:44:55 INFO - 'HOME': '/home/cltbld', 18:44:55 INFO - 'LANG': 'en_US.UTF-8', 18:44:55 INFO - 'LANGUAGE': 'en_US:en', 18:44:55 INFO - 'LOGNAME': 'cltbld', 18:44:55 INFO - 'MAIL': '/var/mail/cltbld', 18:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:44:55 INFO - 'MOZ_NO_REMOTE': '1', 18:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:44:55 INFO - 'NO_EM_RESTART': '1', 18:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:44:55 INFO - 'PWD': '/builds/slave/test', 18:44:55 INFO - 'SHELL': '/bin/bash', 18:44:55 INFO - 'SHLVL': '1', 18:44:55 INFO - 'TERM': 'linux', 18:44:55 INFO - 'TMOUT': '86400', 18:44:55 INFO - 'USER': 'cltbld', 18:44:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:44:55 INFO - '_': '/tools/buildbot/bin/python'} 18:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:44:55 INFO - Cleaning up... 18:44:55 INFO - Return code: 0 18:44:55 INFO - Running post-action listener: _resource_record_post_action 18:44:55 INFO - Running post-action listener: _start_resource_monitoring 18:44:55 INFO - Starting resource monitoring. 18:44:55 INFO - ##### 18:44:55 INFO - ##### Finished create-virtualenv step (success) 18:44:55 INFO - ##### 18:44:55 INFO - ##### 18:44:55 INFO - ##### Running install step. 18:44:55 INFO - ##### 18:44:55 INFO - Running pre-action listener: _resource_record_pre_action 18:44:55 INFO - Running main action method: install 18:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:44:55 INFO - Reading from file tmpfile_stdout 18:44:55 INFO - Detecting whether we're running mozinstall >=1.0... 18:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:44:55 INFO - Reading from file tmpfile_stdout 18:44:55 INFO - Output received: 18:44:55 INFO - Usage: mozinstall [options] installer 18:44:55 INFO - Options: 18:44:55 INFO - -h, --help show this help message and exit 18:44:55 INFO - -d DEST, --destination=DEST 18:44:55 INFO - Directory to install application into. [default: 18:44:55 INFO - "/builds/slave/test"] 18:44:55 INFO - --app=APP Application being installed. [default: firefox] 18:44:55 INFO - mkdir: /builds/slave/test/build/application 18:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:45:12 INFO - Reading from file tmpfile_stdout 18:45:12 INFO - Output received: 18:45:12 INFO - /builds/slave/test/build/application/firefox/firefox 18:45:12 INFO - Running post-action listener: _resource_record_post_action 18:45:12 INFO - ##### 18:45:12 INFO - ##### Finished install step (success) 18:45:12 INFO - ##### 18:45:12 INFO - ##### 18:45:12 INFO - ##### Running run-tests step. 18:45:12 INFO - ##### 18:45:12 INFO - Running pre-action listener: _resource_record_pre_action 18:45:12 INFO - Running main action method: run_tests 18:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:45:12 INFO - Python 2.7.3 18:45:12 INFO - Return code: 0 18:45:12 INFO - grabbing minidump binary from tooltool 18:45:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:45:12 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 0x274d3b0>, '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 0x274e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:45:12 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 18:45:12 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 18:45:12 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 18:45:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:45:12 INFO - Return code: 0 18:45:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:45:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:45:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:45:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:45:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 18:45:12 INFO - Using env: {'DISPLAY': ':0', 18:45:12 INFO - 'HOME': '/home/cltbld', 18:45:12 INFO - 'LANG': 'en_US.UTF-8', 18:45:12 INFO - 'LANGUAGE': 'en_US:en', 18:45:12 INFO - 'LOGNAME': 'cltbld', 18:45:12 INFO - 'MAIL': '/var/mail/cltbld', 18:45:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:45:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:12 INFO - 'MOZ_NO_REMOTE': '1', 18:45:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:12 INFO - 'NO_EM_RESTART': '1', 18:45:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:12 INFO - 'PWD': '/builds/slave/test', 18:45:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:45:12 INFO - 'SHELL': '/bin/bash', 18:45:12 INFO - 'SHLVL': '1', 18:45:12 INFO - 'TERM': 'linux', 18:45:12 INFO - 'TMOUT': '86400', 18:45:12 INFO - 'USER': 'cltbld', 18:45:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835', 18:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:12 INFO - '_': '/tools/buildbot/bin/python'} 18:45:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:45:12 INFO - mozversion application_buildid: 20160707172726 18:45:12 INFO - mozversion application_changeset: 23dc78b7b57e9f91798ea44c242a04e112c37db0 18:45:12 INFO - mozversion application_display_name: Nightly 18:45:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:45:12 INFO - mozversion application_name: Firefox 18:45:12 INFO - mozversion application_remotingname: firefox 18:45:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:45:12 INFO - mozversion application_vendor: Mozilla 18:45:12 INFO - mozversion application_version: 50.0a1 18:45:12 INFO - mozversion platform_buildid: 20160707172726 18:45:12 INFO - mozversion platform_changeset: 23dc78b7b57e9f91798ea44c242a04e112c37db0 18:45:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:45:12 INFO - mozversion platform_version: 50.0a1 18:45:12 INFO - using testdate: 1467942312 18:45:12 INFO - actual date: 1467942312 18:45:12 INFO - starting webserver on 'localhost:54808' 18:45:12 INFO - SUITE-START | Running 2 tests 18:45:12 INFO - TEST-START | dromaeo_css 18:45:12 INFO - operating with platform_type : linux_ 18:45:12 INFO - Initialising browser for dromaeo_css test... 18:45:12 INFO - TEST-INFO | started process 21381 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjHz1uM/profile http://localhost:54808/getInfo.html) 18:45:16 INFO - PROCESS | 21381 | __metrics Screen width/height:1600/1200 18:45:16 INFO - PROCESS | 21381 | colorDepth:24 18:45:16 INFO - PROCESS | 21381 | Browser inner width/height: 1024/697 18:45:16 INFO - PROCESS | 21381 | __metrics 18:45:17 INFO - PROCESS | 21381 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:45:18 INFO - TEST-INFO | 21381: exit 0 18:45:18 INFO - Browser initialized. 18:45:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:45:18 INFO - TEST-INFO | started process 21464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjHz1uM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:30 INFO - PROCESS | 21464 | RSS: Main: 158732288 18:45:30 INFO - PROCESS | 21464 | 18:46:22 INFO - PROCESS | 21464 | 1467942382162 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] 18:48:33 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54808/tests/dromaeo/cssquery-ext.html) 18:48:35 INFO - PROCESS | 21464 | RSS: Main: 263286784 18:48:35 INFO - PROCESS | 21464 | 18:51:38 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-ext.html (next: http://localhost:54808/tests/dromaeo/cssquery-jquery.html) 18:51:38 INFO - PROCESS | 21464 | RSS: Main: 246673408 18:51:38 INFO - PROCESS | 21464 | 18:54:40 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54808/tests/dromaeo/cssquery-mootools.html) 18:54:41 INFO - PROCESS | 21464 | RSS: Main: 246034432 18:54:41 INFO - PROCESS | 21464 | 18:57:43 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54808/tests/dromaeo/cssquery-prototype.html) 18:57:44 INFO - PROCESS | 21464 | RSS: Main: 243265536 18:57:44 INFO - PROCESS | 21464 | 19:00:47 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54808/tests/dromaeo/cssquery-yui.html) 19:00:47 INFO - PROCESS | 21464 | RSS: Main: 212320256 19:00:47 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | Cycle 1(1): loaded http://localhost:54808/tests/dromaeo/cssquery-yui.html (next: http://localhost:54808/tests/dromaeo/cssquery-dojo.html) 19:03:50 INFO - PROCESS | 21464 | RSS: Main: 281145344 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | __start_tp_report 19:03:50 INFO - PROCESS | 21464 | _x_x_mozilla_page_load 19:03:50 INFO - PROCESS | 21464 | _x_x_mozilla_page_load_details 19:03:50 INFO - PROCESS | 21464 | |i|pagename|runs| 19:03:50 INFO - PROCESS | 21464 | |0;dojo.html;2539.7968162547;2564.5255627708875;2580.1485509781774;2582.9225123246297;2604.101584953192;4506.707064040835;4571.200039993005;4584.129015487055;4622.07558488302;4627.167109920213;4600.516945720701;4613.884652883679;4623.329617205512;4674.836380726675;4698.741569213692;4275.508316543591;4281.6146546810705;4296.63478604319;4327.913441731179;4333.761643109628;4331.068820203655;4362.97818510907;4389.736615803046;4397.208502469554;4418.491873434564;4317.481902171759;4355.303151495761;4360.236958532244;4372.540883207265;4395;4198.181354635847;4233.5554766749665;4262.360645903108;4269.082147338338;4311.116221174651;4238.406623072772;4249.1714115747445;4284.186004659136;4295.656347492193;4333.71830830995;4651.000034992493;4660.440747110369;4688.765561721901;4714.198586240347;4728.314394412825;4853.733044682541;4888.608911287089;4940.308356830046;4949.331840201598;5012.1980483122525;5007.599392048627;5016.423111342169;5023.748812559394;5029.17018691919;5040.067587496288;4604.815807367735;4699;4708.034852855157;4713.293006049052;4714.198586240347;5023.949760502422;5053.444121146671;5096.592272618182;5134.178531435027;5174.9482505174465;4914.066327397783;4949.307097006422;4995.25071239317;5000.62495312853;5008.048470790538;4872.43966943806;4974.925376119361;5038.697678139323;5080.907604864898;5089.287499750038;4973.35346404965;4996.600271978233;5007.824726134568;5024.949750502521;5051.898962020739;4958.925616115761;4975.90048199034;4984.626153038536;4986.501349864984;5037.19404895215;4078.327076032481;4102.979485102555;4131.07050913548;4165.520965089054;4212.72617279882;3613.94579081314;3633.5094762206763;3648.416253399443;3666.4133738601695;3704.7962362070343;4297.806598703066;4305.655547556188;4306.80619372129;4346.978265108654;4353.586409291112;4896.290037944478;4943.65394422387;4945.752712364368;5026.497350265017;5081.110805609003;4260.765657888845;4271.167122411162;4278.144371125725;4297.828086876552;4318.438602981592;4420.160169567836;4428.512863585003;4430.224710675619;4437.134758722018;4472.686911916135;4548.340490628906;4574.199515084913;4623.306504024423;4641.118187544355;4722.338872557776;4950.133726597903;4951.158303088411;5001.1498045331655;5005.299258103795;5011.398632164163;4611.815527378867;4612.192866248461;4624.3294722265755;4624.65315101362;4665.836695715634;4727.952720472889;4872.339159243829;4887.511248875084;4901.191303434894;4931.309616653743;4973.403191617029;5041.949580504148;5048.293238946624;5063.949360506348;5110.233464980134;4903.117438860897;4909.4354149273295;4915.4592994771265;4916.557509824133;4960.30555722192;4781.235002399739;4837.298591704112;4865.440474345495;4907.828226012072;4970.229614409773;4826.927596086131;4828.855134345975;4851.95148048515;4857.441394239708;4865.051314993542;4918;4927.507249275044;4928.117051524898;4931.753412329441;4980.452150263541;4707.976460117678;4748.786304616231;4803.807847686054;4805.27920811881;4819.084373968934 19:03:50 INFO - PROCESS | 21464 | |1;ext.html;559.2980809084601;565.5955991466102;568.5820921622609;570.8258981010792;582.9562782791292;27614;39527.011824704394;40534.391984120186;40668.98327541817;43616.164949278274;41098;41128;41341.7932910335;41485;41596;7481.102267727853;7849.764507064783;7988.680452781882;8001.559914204717;8006.9199308006755;7377.7786666399825;7475.140358858719;7506.962465187667;7651.655675494589;7655;10390.480475976208;10476.319039262424;10476.895231047707;10488.475576221197;10492.895071049306;10224.233182511303;10331.328463649914;10337.37975721459;10350.63772767957;10355.482225888712;7469.439792015566;9926.45404502752;10318.484075796181;10349.430781307025;10583.947080264626;37953.62046379528;38250.80874595637;38256.42615360772;38335.42496862549;38437.423438648446;2106.968395474069;2759.7748213870227;2778.659892029215;2831.377097038648;3160.4311223979676;26428;28809;29178.41643167125;29710.10869673913;30109.247268818235;3424.4692072728517;3453;3496.318217947501;3505.071156143624;3527.224010717638;254773;255176.7241163801;255646;256293;257076;46874.76562617233;51502.48497515052;51930.22104668433;52214.477855221725;52297;48442;48696.269555956686;48723.512764872605;48844.75577622089;48856.26715599269;12621.684457888443;12684.04869634781;12728.490860365482;12754.170978886343;12760.489580416679;8867.467951922905;9720.659776907774;9791.608335666637;9796.755081123029;9797.118259356692;13989.230592317317;14022.22877741734;14278;14634.341454634567;14903.850961490247;5524.116141417354;5552.91670624941;5559.9444005560235;5619.971900140473;5648.717564121777;833.2917020532499;904.5432056811268;905.6920646979927;906.4198912695748;956.1251454918811;27132.457350852874;28419.43161136807;28755.856220718764;28876;28992.42015159685;846.1665259719172;914.0767824497173;944.8157609266265;999.5551979369111;1018.7720958024258;14441.35013924376;15261.542153735183;15269.694606107816;15290.388384464497;15528.301226444837;475383;481940;483786;698880;706748;49001;49698.254526182136;49750;49777.25334119991;49799;1103.178132291432;1128.1087940527057;1173.9413029348498;1207.6678913298913;1234.5061975209987;27987.160385188476;29219.85390073036;29257.561136583394;30099.247518811328;30100.54849177308;42566;42915.785421072695;43015.56984430241;43072.35391469068;43193.3520997179;330484;333658;334613;334981;335051;31818.204544886565;32213.35573288568;32258.19354516062;32605.18487037843;32718.34563308768;42095;42740;42785.358219626105;43629;43635.345469817345;37339.62660373362;37339.813300933325;37438.8128059358;37706.62293377031;37741.4338784924;36044.63955360413;36905.44641830321;36906;37633.81183094067;37736.81131594325;36408.08979775527;36410;36821.81589092038;37025.25949481049;37192.628073718915;32489.512657309686;33234.54297380647;33237;33399.83300083484;33749;25130.452101175502;25238.621420679072;25405.34865233754;25424.491510170064;25822.741772582034 19:03:50 INFO - PROCESS | 21464 | |2;jquery.html;350.2976532053233;357.15710922223553;368.7326688151091;379.6810679029616;399.08808372868003;13070.215787052783;14847.703045939088;17174.398896038656;17327.047012414314;17910.194041268143;16439.2602332895;17783.199756010978;17945.192466339013;18032.909835450806;18038.18828152733;5021.447640759514;5199.350081239845;5241.685498870061;5245.921311180336;5259;3775.18833450808;5153.6392452528335;5764.711764411783;6053.576249662526;6100.1459795628425;6095.542834287424;6100.206973093492;6117.755289788403;6129.32577416486;6132.570720049598;4930.457649658535;5224.92162617561;5227.346581677271;5303.098473259555;5308.893822123575;3395.8641654333796;4947.406311242638;5139.460356662571;5237.816676416345;5243.370795504526;11641.534338626445;11779.882201177965;11882.58410955621;12293.631191064282;19487.31794387204;1868.8504919606398;2018.4651067467132;2189.879556624385;2310.2145270608075;2374.3707917401903;8906.064863189402;8930.464172149692;9082.954585227031;9431;9499.430034197901;1751.789785225781;1756.1833747307435;1766.3287950578826;1807.8644101692425;1847.094923487495;63932.57588941623;69424.95862562116;71975.20061998439;72861.63569182174;75409;9679.612815487442;9835.360701554519;9952.900470995342;9953.80092398148;10145.289829711997;13533.45866165362;13635.113717608521;13660.180389176681;13665.795013074812;13675.521356752466;6535.8366040848405;6598.109255250576;6864.416524595365;6877.4841886858685;6956.234814170437;6287.402696743802;6313.3686631336495;6433.067205255305;6458.773942912069;6459.741610335628;4325.870223893288;4342.6742994275555;4404;4405.568190338153;4480.686351955397;1658.4195531563855;1753.7720096387388;1754.3421217043608;1795.371619933039;1850.5188650950581;847.3898792869124;861.095849358184;943.3820847345;943.4056544376998;945.4374647084974;16208.189590520427;17318.393856215225;17496.125193740263;17905.641887162183;17977.640447190985;829.1592664285278;865.1305438034735;870.1516021878617;924.4499522783933;926.4719110107175;9682.75793105178;9807.852882206773;9958.75515560555;9986.700398988041;10163.44101074448;358206;366012;366661;367312;372121;74962;75700.62149689216;75888.24111758811;78570;79497;849.1211595997988;873.724776695339;886.1138861138861;889.644142343055;894.1460904835866;11907.5749644806;15749.212539373215;16208.270627822012;16505.752413714043;16823.41118060911;27213.319667008484;37303.25393492169;38413.423798642485;38580.614193857706;38594.22811543697;46331.30503042525;48225;48412.757936210095;49000;49244.75377623089;7602.6198690066385;7671.923280767121;7724.845503089794;7795.64919578609;7978.960105199669;10996.780064398827;11917.52329906794;12017.09871759604;12033.879661203277;12174.208676436003;10372.22208334393;10372.481375931324;10604.681859544226;10817.56729730802;10855.457227138453;9733.707988760065;9887.703368898945;9990.300678952404;10275.383476991405;10308.690739277834;10490.475476226005;10615.734606634896;10689.198310126616;10717.94641026821;11031.282966607145;7561.4706970513735;7786.33816125613;7804.609769511615;7940.6029698515995;8002.439829211899;8733.606987685696;10592.470376481298;10677.679669609925;10855.511501982272;10956.890431095588 19:03:50 INFO - PROCESS | 21464 | |3;mootools.html;1350.9324533773308;1409.1404243411519;1462.7293950619137;1673.0630846725842;1676.6562854614797;7552.093748750151;7956.641951112199;8032.598370081501;8035.71874984375;8044.477108987911;6925.134358205224;7282.70869165234;7393.963030184842;7425.443091768112;8046.557439340834;2203.4160947348923;2230.4981379189717;2232.263353093483;2232.8995195216253;2233.02863254484;2137.8396620253466;2149.376680762577;2184.1045171479695;2186.682930975008;2194.2320187934174;1858.8977606231651;1921.7982111878262;1926.5568919148604;1933.3619905431244;1935.235581945131;2161.448830548208;2224.12147201856;2225.121077174515;2226.7327920649627;2237.6084185267514;2185.6830759539935;2195.9451013724783;2208.0726095040122;2218.9681797964026;2222.4443889027743;6927.376536111724;7773.183815699326;7969.72105976288;8146.551939643317;8168.4282100253;4567.931481027754;4715.457722361938;4723.622110231173;4725.196716558194;4725.503822098664;2710.4985577668194;2758.2690586994463;2802.1313392848283;2805.9017934372405;2809.0730059080456;858.4291446188278;863.3222920007827;863.7581477186397;1645.4405502129214;1702.9659406811918;10957.56169753209;11089.27919685218;11095.500702468327;11136.276142050741;11152.888471115266;3929.410588411704;3958.386450100239;4028.4964379452567;4108.958910410917;4116.6912481564;8732.214100730966;8752.21230089295;8752.56237188138;8765.167309105625;8774.692885748967;4534.523874993144;4537.636989040869;4569.908601827945;4572.039871626929;4582.175208462442;4178.561251068655;4242.490901091889;4244.384564238168;4245.108527209259;4246.893827654271;3374.3251349730153;3420.3159368126476;3438.7764795288726;3441.9655803442147;3482.6169121396624;995.9053889880595;996.3573495095623;1000.7998400319965;1008.324422636835;1011.2870426349406;662.0797092042066;679.8368391585984;689.5276735436242;689.9068625735462;696.0763018107809;4846.491118432585;4942.085714142895;5029.723365214875;5040.697558146523;5048.873778155576;386.1928569290197;392.04536952520704;396.6806720589895;399.31517447577545;401.81315688205126;3329.550510681027;3443.5695538057744;3464.2725027744455;3485;3513.4202856528896;9677.080677335642;10019.048190421898;10024.14794742455;10037.548310326052;10072.446015469073;9913.306068575275;9937.354071985297;9949.253805964581;9963.452010139366;9972;6872.209695884937;7186.389156921616;7487.475876688688;7582.696692132253;7588.468807183444;5425.83722488326;5427.4301198373605;5470.152126420411;5574.5261652758345;5596.272484576979;9210.355275130676;9303.209227215762;9409.059094090537;9440.622375104918;9445.763855903657;9039.95480022622;9616;9642.325037247325;9658.468784216935;9665.855012174683;6911.516193866381;6979.441644668516;7011.474139439664;7014.789556313319;7014.824629384306;7212;7301.963490182515;7312.488125831141;7319.63401829917;7323.890141647986;6517.283098859027;6683.264840867403;6704.899426508704;6759.797206083826;6793.218779840382;6479.643619600967;6507.316731743288;6539.149910511603;6631.535792494479;6835.726570937306;6003.7898673546215;6076.969615151896;6083.939160608337;6131.877362452815;6136.6011209271255;4308.418363520885;4345.2395830728865;4403.933940990823;4441.689081764374;4468.597826195658;4394.560543945579;4402.449693788277;4416.889577760582;4448.243798554318;4477.126960242721 19:03:50 INFO - PROCESS | 21464 | |4;prototype.html;302.4435039527269;305.47763324714737;306.8128441650594;308.60498561840825;313.026487623491;4922.409310882695;4947.208446648537;4963.801447942083;4967.155583550801;5000.274960130779;4834.637402194832;4913.410390753105;4916.1888288432365;4920.950790492103;4926.768307923019;3641.5083963664897;3689.8524059037604;3759.6653188118235;3800.2589495048337;3859.710521710883;3769.5853456119803;3798.8480460781675;3814.370628846237;3824.508441707736;3848.6921046316365;3628.8024951765988;3799.9620003800023;3802.1065049713297;3817.9192340174673;3825.387937929932;3574.963260654407;3760.6051364606597;3765.6799172070405;3776.8678096266635;3844.4233364995193;3403.4894765785084;3407.2163402417336;3408.5739282589675;3413.300273443938;3447.258839349552;4731.905361892777;4734.550217729345;4748;4749.3588365570895;4771.28430735389;4305.806238719265;4368.432103826514;4390.670699697535;4405.163019026407;4431.667624928111;5096;5108.390856880071;5122.871928201787;5124.974375128138;5129.256257842592;3704.629537046301;3718.3121122592406;3756.136088699587;3763.849446022156;3767.792771397572;5136;5269.446708095671;5286.973565132188;5370.624056316059;5372.113601255788;3778.8299526521373;3784.2242340320718;3784.924301513954;3805.6765174959883;3841.8079096045644;3866.9806650966566;3867.9226415471535;3884.4561761353434;3891.280113179071;3895.7078219133678;4341.413909122291;4348.717333373321;4353.25994580922;4376.452943382077;4383.408239887638;4857.247126695368;5086.72023038729;5088.618353623419;5092.898142037138;5105.540501354896;1285.884270415667;1286.5625687266188;1290.9612711618665;1300.3368282175968;1306.1183701001785;800.4316934976116;824.7938015496126;830.3647709502236;834.8831163637095;845.3364109174391;4228.703990720682;4378.80295386702;4485.394471746273;4485.416895803524;4489.304157855538;5250.4749525047955;5272.2355258487305;5290.947090529123;5297.152455607084;5317.229001794718;4461.107778444324;4461.531539188403;4462.330650402466;4469.329600559942;4471.821127154877;2651.5757478803685;2773.8751756171023;2777.666679998413;2780.6225677045836;2797.1328888044773;4825.396825396825;4844.249141383091;4864.829730959399;4872.415310162733;4889.315495830587;5134.094306560669;5346.278252435949;5359.973200133974;5381.9461805382225;5383.7388296914605;4872.829450969199;4890.381117972745;4894.804207831789;4895.632827537949;4899.951000489949;5219.582433405394;5245.213218017328;5246.711430871186;5252.711100889488;5269.762860671202;4972.502749725143;5079.933214025096;5105.030044291572;5162.303089082926;5177.223416487557;4861.708297502161;4899.3385892904;4916.7541622919425;4924.498028101463;4924.704517728947;3704.7962362070343;3723.7579557328686;3725.7950812705562;3749.287635349384;3761.097336639242;5109.744512774272;5343.65266257692;5352.9732351338;5361.865953351197;5379.838604841861;5103.234514822806;5116.411612664591;5117.667351622133;5125.308083408692;5213.869653258699;5110.182370820651;5115.769790359368;5120.692758434506;5122.410922743932;5133.460986596503;4872.366592342973;4954.702717836942;5031.144705400164;5045.722485263199;5060.696358218519;4870.878228044186;4963.354763880672;5115.590752739847;5122.8207012755865;5147.716875571879;4985.631824883297;4997.650164488451;4999.800007999639;5006.299118123538;5043.42000669913 19:03:50 INFO - PROCESS | 21464 | |5;yui.html;578.756922092721;590.4007432456056;600.3696119074973;628.7296462521114;633.9809805705828;11289.20975531712;12066.457009434575;12406.81389779154;12500.937495312523;12505.124641275115;11767.529298828036;11806.527738890456;11950.940245298762;11954.402279885993;11973.521059157645;606.702715669321;620.5780069552702;745.1803016681633;765.885117232414;770.8959290495767;744.4193529047325;767.758156180804;768.9538627682357;774.1987043410044;775.1899265267781;809.2312303311877;809.4778867630375;814.2752949874616;814.3241109878786;814.9592520373988;640.1805688718448;657.3984803904422;806.371030596133;807.7657479330987;809.6599428240124;483.6445212768612;484.08991096737907;569.4760820045568;584.2200662116038;585.166138252992;12278.386080696018;12287.877121228852;12308.199967002205;12333.444994975158;12346.5061397544;12742.171758835739;13327.133736307109;13384.79922801149;13395.196288222738;13534.390952407068;11937.701557461047;12113.869178554853;12257.877421225763;12482.875171248263;12895.484180632764;4179.6029377209115;4204.915901681949;4253.1706317268145;4272.5086615039045;4345.41336919515;60372.094418584194;60450.395496045356;60972.39027609756;61018.38981610171;61282.69358653223;9935.751606209902;10136.2904596679;10176.389416635026;10214.438205898598;10269.127124194527;16330.265138068815;16417.91791041037;16467.917660411622;16547.83452165487;16559.917200414162;6465.515086368447;6489.383508566679;6501.934980650228;6511.706973186218;6652.0354548590185;3446.4141096013736;3450.0857272822723;3546.7517273790586;3554.893353199408;3569.1077230692326;2704.229294651015;2797.3006748312923;2798.1885253276323;2798.426322603902;2803.5794630805544;480.47628085386395;482.6404328775015;490.1642699197859;490.399260905388;491.13069866336383;516.4886762105489;527.1749711701175;528.3738769558086;533.8937890023818;538.6256551696533;7169.139703235645;7386.519876208029;7629.618519074135;8002.599870006593;8032.39757018226;496.6895690193629;521.4941506738458;538.284082170712;574.5633318677751;600.1837500998638;3890.435886796399;3943.2704949583845;4208.852690155891;4285.97857010713;4307.203167413976;93423;93440.53279733694;93582.51339892793;94586;96143.51928240454;82509.58745206236;85988.5700571493;86286.56856715676;86481;86657;67663.66168169127;70655;71785.64107179535;73856.89214661784;73912.26087739161;8024.518528888286;8036.959815200887;8056.509545734058;8073.071596766212;8357.206065423776;43289.35065974076;43441;45059.54940450685;45201;45529;41420.378694320214;41581.58418415898;42908.78545607252;57537;62925.68537157285;9659.613615455302;9767.218622510325;9885.604575816888;9974.351667141902;10001.449920254456;11493.54025838957;11565.36390498497;11794.76410471803;11987;12102.515899363927;10490.737731556772;10548.314359566604;10578.947105264424;10993.945030274797;11300.208985370946;11008.834867477155;11069.446527673745;11424.714382140513;11576.594819181288;11596.594119205787;10708.518116684612;10990.395528245703;11046.668599942015;11591.826122608;11617.535298587962;9096.727098187064;9268.860967085364;9323.300752443458;9363.320176248428;9442.244620430212;9230.261579073793;9321.813563728552;9499.430034197694;9753.60985560598;9813.046778252638 19:03:50 INFO - PROCESS | 21464 | __end_tp_report 19:03:50 INFO - PROCESS | 21464 | __start_cc_report 19:03:50 INFO - PROCESS | 21464 | _x_x_mozilla_cycle_collect,984 19:03:50 INFO - PROCESS | 21464 | __end_cc_report 19:03:50 INFO - PROCESS | 21464 | __startTimestamp1467943430963__endTimestamp 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | ------- Summary: start ------- 19:03:50 INFO - PROCESS | 21464 | Number of tests: 6 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#0] dojo.html Cycles:165 Average:4609.11 Median:4714.20 stddev:494.08 (10.5%) stddev-sans-first:468.17 19:03:50 INFO - PROCESS | 21464 | Values: 2539.8 2564.5 2580.1 2582.9 2604.1 4506.7 4571.2 4584.1 4622.1 4627.2 4600.5 4613.9 4623.3 4674.8 4698.7 4275.5 4281.6 4296.6 4327.9 4333.8 4331.1 4363.0 4389.7 4397.2 4418.5 4317.5 4355.3 4360.2 4372.5 4395.0 4198.2 4233.6 4262.4 4269.1 4311.1 4238.4 4249.2 4284.2 4295.7 4333.7 4651.0 4660.4 4688.8 4714.2 4728.3 4853.7 4888.6 4940.3 4949.3 5012.2 5007.6 5016.4 5023.7 5029.2 5040.1 4604.8 4699.0 4708.0 4713.3 4714.2 5023.9 5053.4 5096.6 5134.2 5174.9 4914.1 4949.3 4995.3 5000.6 5008.0 4872.4 4974.9 5038.7 5080.9 5089.3 4973.4 4996.6 5007.8 5024.9 5051.9 4958.9 4975.9 4984.6 4986.5 5037.2 4078.3 4103.0 4131.1 4165.5 4212.7 3613.9 3633.5 3648.4 3666.4 3704.8 4297.8 4305.7 4306.8 4347.0 4353.6 4896.3 4943.7 4945.8 5026.5 5081.1 4260.8 4271.2 4278.1 4297.8 4318.4 4420.2 4428.5 4430.2 4437.1 4472.7 4548.3 4574.2 4623.3 4641.1 4722.3 4950.1 4951.2 5001.1 5005.3 5011.4 4611.8 4612.2 4624.3 4624.7 4665.8 4728.0 4872.3 4887.5 4901.2 4931.3 4973.4 5041.9 5048.3 5063.9 5110.2 4903.1 4909.4 4915.5 4916.6 4960.3 4781.2 4837.3 4865.4 4907.8 4970.2 4826.9 4828.9 4852.0 4857.4 4865.1 4918.0 4927.5 4928.1 4931.8 4980.5 4708.0 4748.8 4803.8 4805.3 4819.1 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#1] ext.html Cycles:180 Average:53169.10 Median:36965.63 stddev:110548.67 (299.1%) stddev-sans-first:110788.22 19:03:50 INFO - PROCESS | 21464 | Values: 559.3 565.6 568.6 570.8 583.0 27614.0 39527.0 40534.4 40669.0 43616.2 41098.0 41128.0 41341.8 41485.0 41596.0 7481.1 7849.8 7988.7 8001.6 8006.9 7377.8 7475.1 7507.0 7651.7 7655.0 10390.5 10476.3 10476.9 10488.5 10492.9 10224.2 10331.3 10337.4 10350.6 10355.5 7469.4 9926.5 10318.5 10349.4 10583.9 37953.6 38250.8 38256.4 38335.4 38437.4 2107.0 2759.8 2778.7 2831.4 3160.4 26428.0 28809.0 29178.4 29710.1 30109.2 3424.5 3453.0 3496.3 3505.1 3527.2 254773.0 255176.7 255646.0 256293.0 257076.0 46874.8 51502.5 51930.2 52214.5 52297.0 48442.0 48696.3 48723.5 48844.8 48856.3 12621.7 12684.0 12728.5 12754.2 12760.5 8867.5 9720.7 9791.6 9796.8 9797.1 13989.2 14022.2 14278.0 14634.3 14903.9 5524.1 5552.9 5559.9 5620.0 5648.7 833.3 904.5 905.7 906.4 956.1 27132.5 28419.4 28755.9 28876.0 28992.4 846.2 914.1 944.8 999.6 1018.8 14441.4 15261.5 15269.7 15290.4 15528.3 475383.0 481940.0 483786.0 698880.0 706748.0 49001.0 49698.3 49750.0 49777.3 49799.0 1103.2 1128.1 1173.9 1207.7 1234.5 27987.2 29219.9 29257.6 30099.2 30100.5 42566.0 42915.8 43015.6 43072.4 43193.4 330484.0 333658.0 334613.0 334981.0 335051.0 31818.2 32213.4 32258.2 32605.2 32718.3 42095.0 42740.0 42785.4 43629.0 43635.3 37339.6 37339.8 37438.8 37706.6 37741.4 36044.6 36905.4 36906.0 37633.8 37736.8 36408.1 36410.0 36821.8 37025.3 37192.6 32489.5 33234.5 33237.0 33399.8 33749.0 25130.5 25238.6 25405.3 25424.5 25822.7 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#2] jquery.html Cycles:180 Average:23406.55 Median:47278.15 stddev:60633.51 (128.2%) stddev-sans-first:60778.89 19:03:50 INFO - PROCESS | 21464 | Values: 350.3 357.2 368.7 379.7 399.1 13070.2 14847.7 17174.4 17327.0 17910.2 16439.3 17783.2 17945.2 18032.9 18038.2 5021.4 5199.4 5241.7 5245.9 5259.0 3775.2 5153.6 5764.7 6053.6 6100.1 6095.5 6100.2 6117.8 6129.3 6132.6 4930.5 5224.9 5227.3 5303.1 5308.9 3395.9 4947.4 5139.5 5237.8 5243.4 11641.5 11779.9 11882.6 12293.6 19487.3 1868.9 2018.5 2189.9 2310.2 2374.4 8906.1 8930.5 9083.0 9431.0 9499.4 1751.8 1756.2 1766.3 1807.9 1847.1 63932.6 69425.0 71975.2 72861.6 75409.0 9679.6 9835.4 9952.9 9953.8 10145.3 13533.5 13635.1 13660.2 13665.8 13675.5 6535.8 6598.1 6864.4 6877.5 6956.2 6287.4 6313.4 6433.1 6458.8 6459.7 4325.9 4342.7 4404.0 4405.6 4480.7 1658.4 1753.8 1754.3 1795.4 1850.5 847.4 861.1 943.4 943.4 945.4 16208.2 17318.4 17496.1 17905.6 17977.6 829.2 865.1 870.2 924.4 926.5 9682.8 9807.9 9958.8 9986.7 10163.4 358206.0 366012.0 366661.0 367312.0 372121.0 74962.0 75700.6 75888.2 78570.0 79497.0 849.1 873.7 886.1 889.6 894.1 11907.6 15749.2 16208.3 16505.8 16823.4 27213.3 37303.3 38413.4 38580.6 38594.2 46331.3 48225.0 48412.8 49000.0 49244.8 7602.6 7671.9 7724.8 7795.6 7979.0 10996.8 11917.5 12017.1 12033.9 12174.2 10372.2 10372.5 10604.7 10817.6 10855.5 9733.7 9887.7 9990.3 10275.4 10308.7 10490.5 10615.7 10689.2 10717.9 11031.3 7561.5 7786.3 7804.6 7940.6 8002.4 8733.6 10592.5 10677.7 10855.5 10956.9 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#3] mootools.html Cycles:180 Average:5132.29 Median:4719.54 stddev:2986.50 (63.3%) stddev-sans-first:2981.36 19:03:50 INFO - PROCESS | 21464 | Values: 1350.9 1409.1 1462.7 1673.1 1676.7 7552.1 7956.6 8032.6 8035.7 8044.5 6925.1 7282.7 7394.0 7425.4 8046.6 2203.4 2230.5 2232.3 2232.9 2233.0 2137.8 2149.4 2184.1 2186.7 2194.2 1858.9 1921.8 1926.6 1933.4 1935.2 2161.4 2224.1 2225.1 2226.7 2237.6 2185.7 2195.9 2208.1 2219.0 2222.4 6927.4 7773.2 7969.7 8146.6 8168.4 4567.9 4715.5 4723.6 4725.2 4725.5 2710.5 2758.3 2802.1 2805.9 2809.1 858.4 863.3 863.8 1645.4 1703.0 10957.6 11089.3 11095.5 11136.3 11152.9 3929.4 3958.4 4028.5 4109.0 4116.7 8732.2 8752.2 8752.6 8765.2 8774.7 4534.5 4537.6 4569.9 4572.0 4582.2 4178.6 4242.5 4244.4 4245.1 4246.9 3374.3 3420.3 3438.8 3442.0 3482.6 995.9 996.4 1000.8 1008.3 1011.3 662.1 679.8 689.5 689.9 696.1 4846.5 4942.1 5029.7 5040.7 5048.9 386.2 392.0 396.7 399.3 401.8 3329.6 3443.6 3464.3 3485.0 3513.4 9677.1 10019.0 10024.1 10037.5 10072.4 9913.3 9937.4 9949.3 9963.5 9972.0 6872.2 7186.4 7487.5 7582.7 7588.5 5425.8 5427.4 5470.2 5574.5 5596.3 9210.4 9303.2 9409.1 9440.6 9445.8 9040.0 9616.0 9642.3 9658.5 9665.9 6911.5 6979.4 7011.5 7014.8 7014.8 7212.0 7302.0 7312.5 7319.6 7323.9 6517.3 6683.3 6704.9 6759.8 6793.2 6479.6 6507.3 6539.1 6631.5 6835.7 6003.8 6077.0 6083.9 6131.9 6136.6 4308.4 4345.2 4403.9 4441.7 4468.6 4394.6 4402.4 4416.9 4448.2 4477.1 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#4] prototype.html Cycles:180 Average:4242.58 Median:4867.85 stddev:1229.51 (25.3%) stddev-sans-first:1196.86 19:03:50 INFO - PROCESS | 21464 | Values: 302.4 305.5 306.8 308.6 313.0 4922.4 4947.2 4963.8 4967.2 5000.3 4834.6 4913.4 4916.2 4921.0 4926.8 3641.5 3689.9 3759.7 3800.3 3859.7 3769.6 3798.8 3814.4 3824.5 3848.7 3628.8 3800.0 3802.1 3817.9 3825.4 3575.0 3760.6 3765.7 3776.9 3844.4 3403.5 3407.2 3408.6 3413.3 3447.3 4731.9 4734.6 4748.0 4749.4 4771.3 4305.8 4368.4 4390.7 4405.2 4431.7 5096.0 5108.4 5122.9 5125.0 5129.3 3704.6 3718.3 3756.1 3763.8 3767.8 5136.0 5269.4 5287.0 5370.6 5372.1 3778.8 3784.2 3784.9 3805.7 3841.8 3867.0 3867.9 3884.5 3891.3 3895.7 4341.4 4348.7 4353.3 4376.5 4383.4 4857.2 5086.7 5088.6 5092.9 5105.5 1285.9 1286.6 1291.0 1300.3 1306.1 800.4 824.8 830.4 834.9 845.3 4228.7 4378.8 4485.4 4485.4 4489.3 5250.5 5272.2 5290.9 5297.2 5317.2 4461.1 4461.5 4462.3 4469.3 4471.8 2651.6 2773.9 2777.7 2780.6 2797.1 4825.4 4844.2 4864.8 4872.4 4889.3 5134.1 5346.3 5360.0 5381.9 5383.7 4872.8 4890.4 4894.8 4895.6 4900.0 5219.6 5245.2 5246.7 5252.7 5269.8 4972.5 5079.9 5105.0 5162.3 5177.2 4861.7 4899.3 4916.8 4924.5 4924.7 3704.8 3723.8 3725.8 3749.3 3761.1 5109.7 5343.7 5353.0 5361.9 5379.8 5103.2 5116.4 5117.7 5125.3 5213.9 5110.2 5115.8 5120.7 5122.4 5133.5 4872.4 4954.7 5031.1 5045.7 5060.7 4870.9 4963.4 5115.6 5122.8 5147.7 4985.6 4997.7 4999.8 5006.3 5043.4 19:03:50 INFO - PROCESS | 21464 | 19:03:50 INFO - PROCESS | 21464 | [#5] yui.html Cycles:180 Average:16973.19 Median:506.59 stddev:24398.12 (4816.2%) stddev-sans-first:24435.51 19:03:50 INFO - PROCESS | 21464 | Values: 578.8 590.4 600.4 628.7 634.0 11289.2 12066.5 12406.8 12500.9 12505.1 11767.5 11806.5 11950.9 11954.4 11973.5 606.7 620.6 745.2 765.9 770.9 744.4 767.8 769.0 774.2 775.2 809.2 809.5 814.3 814.3 815.0 640.2 657.4 806.4 807.8 809.7 483.6 484.1 569.5 584.2 585.2 12278.4 12287.9 12308.2 12333.4 12346.5 12742.2 13327.1 13384.8 13395.2 13534.4 11937.7 12113.9 12257.9 12482.9 12895.5 4179.6 4204.9 4253.2 4272.5 4345.4 60372.1 60450.4 60972.4 61018.4 61282.7 9935.8 10136.3 10176.4 10214.4 10269.1 16330.3 16417.9 16467.9 16547.8 16559.9 6465.5 6489.4 6501.9 6511.7 6652.0 3446.4 3450.1 3546.8 3554.9 3569.1 2704.2 2797.3 2798.2 2798.4 2803.6 480.5 482.6 490.2 490.4 491.1 516.5 527.2 528.4 533.9 538.6 7169.1 7386.5 7629.6 8002.6 8032.4 496.7 521.5 538.3 574.6 600.2 3890.4 3943.3 4208.9 4286.0 4307.2 93423.0 93440.5 93582.5 94586.0 96143.5 82509.6 85988.6 86286.6 86481.0 86657.0 67663.7 70655.0 71785.6 73856.9 73912.3 8024.5 8037.0 8056.5 8073.1 8357.2 43289.4 43441.0 45059.5 45201.0 45529.0 41420.4 41581.6 42908.8 57537.0 62925.7 9659.6 9767.2 9885.6 9974.4 10001.4 11493.5 11565.4 11794.8 11987.0 12102.5 10490.7 10548.3 10578.9 10993.9 11300.2 11008.8 11069.4 11424.7 11576.6 11596.6 10708.5 10990.4 11046.7 11591.8 11617.5 9096.7 9268.9 9323.3 9363.3 9442.2 9230.3 9321.8 9499.4 9753.6 9813.0 19:03:50 INFO - PROCESS | 21464 | -------- Summary: end -------- 19:03:50 INFO - PROCESS | 21464 | 19:03:51 INFO - TEST-INFO | 21464: exit 0 19:03:51 INFO - TEST-OK | dromaeo_css | took 1119308ms 19:03:51 INFO - TEST-START | kraken 19:03:51 INFO - operating with platform_type : linux_ 19:03:51 INFO - Initialising browser for kraken test... 19:03:51 INFO - TEST-INFO | started process 21652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0CkfXA/profile http://localhost:54808/getInfo.html) 19:03:55 INFO - PROCESS | 21652 | __metrics Screen width/height:1600/1200 19:03:55 INFO - PROCESS | 21652 | colorDepth:24 19:03:55 INFO - PROCESS | 21652 | Browser inner width/height: 1024/697 19:03:55 INFO - PROCESS | 21652 | __metrics 19:03:56 INFO - PROCESS | 21652 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:03:57 INFO - TEST-INFO | 21652: exit 0 19:03:57 INFO - Browser initialized. 19:03:57 INFO - Running cycle 1/1 for kraken test... 19:03:57 INFO - TEST-INFO | started process 21733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0CkfXA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:09 INFO - PROCESS | 21733 | RSS: Main: 158605312 19:04:09 INFO - PROCESS | 21733 | 19:04:12 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54808/tests/kraken/driver.html?testName=audio-beat-detection) 19:04:13 INFO - PROCESS | 21733 | RSS: Main: 216637440 19:04:13 INFO - PROCESS | 21733 | 19:04:15 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54808/tests/kraken/driver.html?testName=audio-dft) 19:04:16 INFO - PROCESS | 21733 | RSS: Main: 203915264 19:04:16 INFO - PROCESS | 21733 | 19:04:19 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54808/tests/kraken/driver.html?testName=audio-fft) 19:04:20 INFO - PROCESS | 21733 | RSS: Main: 271310848 19:04:20 INFO - PROCESS | 21733 | 19:04:22 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54808/tests/kraken/driver.html?testName=audio-oscillator) 19:04:23 INFO - PROCESS | 21733 | RSS: Main: 181755904 19:04:23 INFO - PROCESS | 21733 | 19:04:25 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54808/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:04:26 INFO - PROCESS | 21733 | RSS: Main: 185696256 19:04:26 INFO - PROCESS | 21733 | 19:04:29 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54808/tests/kraken/driver.html?testName=imaging-darkroom) 19:04:29 INFO - PROCESS | 21733 | RSS: Main: 221868032 19:04:29 INFO - PROCESS | 21733 | 19:04:33 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54808/tests/kraken/driver.html?testName=imaging-desaturate) 19:04:34 INFO - PROCESS | 21733 | RSS: Main: 217485312 19:04:34 INFO - PROCESS | 21733 | 19:04:36 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54808/tests/kraken/driver.html?testName=json-parse-financial) 19:04:37 INFO - PROCESS | 21733 | RSS: Main: 219877376 19:04:37 INFO - PROCESS | 21733 | 19:04:39 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54808/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:04:39 INFO - PROCESS | 21733 | RSS: Main: 205344768 19:04:39 INFO - PROCESS | 21733 | 19:04:41 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:04:42 INFO - PROCESS | 21733 | RSS: Main: 209891328 19:04:42 INFO - PROCESS | 21733 | 19:04:44 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:04:45 INFO - PROCESS | 21733 | RSS: Main: 212557824 19:04:45 INFO - PROCESS | 21733 | 19:04:47 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:04:48 INFO - PROCESS | 21733 | RSS: Main: 225738752 19:04:48 INFO - PROCESS | 21733 | 19:04:51 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:04:52 INFO - PROCESS | 21733 | RSS: Main: 224661504 19:04:52 INFO - PROCESS | 21733 | 19:04:53 INFO - PROCESS | 21733 | Cycle 1(1): loaded http://localhost:54808/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54808/tests/kraken/driver.html?testName=ai-astar) 19:04:54 INFO - PROCESS | 21733 | RSS: Main: 266555392 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | __start_tp_report 19:04:54 INFO - PROCESS | 21733 | _x_x_mozilla_page_load 19:04:54 INFO - PROCESS | 21733 | _x_x_mozilla_page_load_details 19:04:54 INFO - PROCESS | 21733 | |i|pagename|runs| 19:04:54 INFO - PROCESS | 21733 | |0;ai-astar;104;114;114;114;115;114;113;116;112;114 19:04:54 INFO - PROCESS | 21733 | |1;audio-beat-detection;148;124;125;185;122;123;142;122;158;121 19:04:54 INFO - PROCESS | 21733 | |2;audio-dft;160;161;161;161;163;162;161;161;163;161 19:04:54 INFO - PROCESS | 21733 | |3;audio-fft;76;75;76;107;79;77;76;76;97;76 19:04:54 INFO - PROCESS | 21733 | |4;audio-oscillator;84;86;116;86;86;85;121;85;85;130 19:04:54 INFO - PROCESS | 21733 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 19:04:54 INFO - PROCESS | 21733 | |6;imaging-darkroom;146;147;147;146;146;146;147;147;147;148 19:04:54 INFO - PROCESS | 21733 | |7;imaging-desaturate;94;96;96;95;94;94;95;94;95;95 19:04:54 INFO - PROCESS | 21733 | |8;json-parse-financial;64;64;65;65;66;65;65;65;64;64 19:04:54 INFO - PROCESS | 21733 | |9;json-stringify-tinderbox;64;65;65;65;64;64;66;65;64;65 19:04:54 INFO - PROCESS | 21733 | |10;stanford-crypto-aes;89;82;78;82;78;79;85;79;80;78 19:04:54 INFO - PROCESS | 21733 | |11;stanford-crypto-ccm;134;125;127;130;139;127;136;142;162;132 19:04:54 INFO - PROCESS | 21733 | |12;stanford-crypto-pbkdf2;170;162;162;163;169;165;163;163;164;163 19:04:54 INFO - PROCESS | 21733 | |13;stanford-crypto-sha256-iterative;67;68;72;67;68;66;66;65;69;66 19:04:54 INFO - PROCESS | 21733 | __end_tp_report 19:04:54 INFO - PROCESS | 21733 | __start_cc_report 19:04:54 INFO - PROCESS | 21733 | _x_x_mozilla_cycle_collect,1949 19:04:54 INFO - PROCESS | 21733 | __end_cc_report 19:04:54 INFO - PROCESS | 21733 | __startTimestamp1467943494083__endTimestamp 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | ------- Summary: start ------- 19:04:54 INFO - PROCESS | 21733 | Number of tests: 14 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.33 (2.9%) stddev-sans-first:1.12 19:04:54 INFO - PROCESS | 21733 | Values: 104.0 114.0 114.0 114.0 115.0 114.0 113.0 116.0 112.0 114.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:133.50 stddev:21.36 (16.0%) stddev-sans-first:22.28 19:04:54 INFO - PROCESS | 21733 | Values: 148.0 124.0 125.0 185.0 122.0 123.0 142.0 122.0 158.0 121.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 19:04:54 INFO - PROCESS | 21733 | Values: 160.0 161.0 161.0 161.0 163.0 162.0 161.0 161.0 163.0 161.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#3] audio-fft Cycles:10 Average:81.50 Median:76.00 stddev:11.11 (14.6%) stddev-sans-first:11.60 19:04:54 INFO - PROCESS | 21733 | Values: 76.0 75.0 76.0 107.0 79.0 77.0 76.0 76.0 97.0 76.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:85.00 stddev:18.22 (21.4%) stddev-sans-first:18.76 19:04:54 INFO - PROCESS | 21733 | Values: 84.0 86.0 116.0 86.0 86.0 85.0 121.0 85.0 85.0 130.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:04:54 INFO - PROCESS | 21733 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 19:04:54 INFO - PROCESS | 21733 | Values: 146.0 147.0 147.0 146.0 146.0 146.0 147.0 147.0 147.0 148.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 19:04:54 INFO - PROCESS | 21733 | Values: 94.0 96.0 96.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 19:04:54 INFO - PROCESS | 21733 | Values: 64.0 64.0 65.0 65.0 66.0 65.0 65.0 65.0 64.0 64.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 19:04:54 INFO - PROCESS | 21733 | Values: 64.0 65.0 65.0 65.0 64.0 64.0 66.0 65.0 64.0 65.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:3.62 (4.5%) stddev-sans-first:2.42 19:04:54 INFO - PROCESS | 21733 | Values: 89.0 82.0 78.0 82.0 78.0 79.0 85.0 79.0 80.0 78.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:135.00 stddev:10.83 (8.0%) stddev-sans-first:11.48 19:04:54 INFO - PROCESS | 21733 | Values: 134.0 125.0 127.0 130.0 139.0 127.0 136.0 142.0 162.0 132.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:163.50 stddev:2.84 (1.7%) stddev-sans-first:2.17 19:04:54 INFO - PROCESS | 21733 | Values: 170.0 162.0 162.0 163.0 169.0 165.0 163.0 163.0 164.0 163.0 19:04:54 INFO - PROCESS | 21733 | 19:04:54 INFO - PROCESS | 21733 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:2.01 (3.0%) stddev-sans-first:2.13 19:04:54 INFO - PROCESS | 21733 | Values: 67.0 68.0 72.0 67.0 68.0 66.0 66.0 65.0 69.0 66.0 19:04:54 INFO - PROCESS | 21733 | -------- Summary: end -------- 19:04:54 INFO - PROCESS | 21733 | 19:04:55 INFO - TEST-INFO | 21733: exit 0 19:04:55 INFO - TEST-OK | kraken | took 63396ms 19:04:55 INFO - SUITE-END | took 1182s 19:04:55 INFO - Completed test suite (00:19:42) 19:04:55 INFO - javascript benchmark 19:04:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2539.7968162547, 2564.5255627708875, 2580.1485509781774, 2582.9225123246297, 2604.101584953192, 4506.707064040835, 4571.200039993005, 4584.129015487055, 4622.07558488302, 4627.167109920213, 4600.516945720701, 4613.884652883679, 4623.329617205512, 4674.836380726675, 4698.741569213692, 4275.508316543591, 4281.6146546810705, 4296.63478604319, 4327.913441731179, 4333.761643109628, 4331.068820203655, 4362.97818510907, 4389.736615803046, 4397.208502469554, 4418.491873434564, 4317.481902171759, 4355.303151495761, 4360.236958532244, 4372.540883207265, 4395.0, 4198.181354635847, 4233.5554766749665, 4262.360645903108, 4269.082147338338, 4311.116221174651, 4238.406623072772, 4249.1714115747445, 4284.186004659136, 4295.656347492193, 4333.71830830995, 4651.000034992493, 4660.440747110369, 4688.765561721901, 4714.198586240347, 4728.314394412825, 4853.733044682541, 4888.608911287089, 4940.308356830046, 4949.331840201598, 5012.1980483122525, 5007.599392048627, 5016.423111342169, 5023.748812559394, 5029.17018691919, 5040.067587496288, 4604.815807367735, 4699.0, 4708.034852855157, 4713.293006049052, 4714.198586240347, 5023.949760502422, 5053.444121146671, 5096.592272618182, 5134.178531435027, 5174.9482505174465, 4914.066327397783, 4949.307097006422, 4995.25071239317, 5000.62495312853, 5008.048470790538, 4872.43966943806, 4974.925376119361, 5038.697678139323, 5080.907604864898, 5089.287499750038, 4973.35346404965, 4996.600271978233, 5007.824726134568, 5024.949750502521, 5051.898962020739, 4958.925616115761, 4975.90048199034, 4984.626153038536, 4986.501349864984, 5037.19404895215, 4078.327076032481, 4102.979485102555, 4131.07050913548, 4165.520965089054, 4212.72617279882, 3613.94579081314, 3633.5094762206763, 3648.416253399443, 3666.4133738601695, 3704.7962362070343, 4297.806598703066, 4305.655547556188, 4306.80619372129, 4346.978265108654, 4353.586409291112, 4896.290037944478, 4943.65394422387, 4945.752712364368, 5026.497350265017, 5081.110805609003, 4260.765657888845, 4271.167122411162, 4278.144371125725, 4297.828086876552, 4318.438602981592, 4420.160169567836, 4428.512863585003, 4430.224710675619, 4437.134758722018, 4472.686911916135, 4548.340490628906, 4574.199515084913, 4623.306504024423, 4641.118187544355, 4722.338872557776, 4950.133726597903, 4951.158303088411, 5001.1498045331655, 5005.299258103795, 5011.398632164163, 4611.815527378867, 4612.192866248461, 4624.3294722265755, 4624.65315101362, 4665.836695715634, 4727.952720472889, 4872.339159243829, 4887.511248875084, 4901.191303434894, 4931.309616653743, 4973.403191617029, 5041.949580504148, 5048.293238946624, 5063.949360506348, 5110.233464980134, 4903.117438860897, 4909.4354149273295, 4915.4592994771265, 4916.557509824133, 4960.30555722192, 4781.235002399739, 4837.298591704112, 4865.440474345495, 4907.828226012072, 4970.229614409773, 4826.927596086131, 4828.855134345975, 4851.95148048515, 4857.441394239708, 4865.051314993542, 4918.0, 4927.507249275044, 4928.117051524898, 4931.753412329441, 4980.452150263541, 4707.976460117678, 4748.786304616231, 4803.807847686054, 4805.27920811881, 4819.084373968934], "value": 4576.701539765544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.2980809084601, 565.5955991466102, 568.5820921622609, 570.8258981010792, 582.9562782791292, 27614.0, 39527.011824704394, 40534.391984120186, 40668.98327541817, 43616.164949278274, 41098.0, 41128.0, 41341.7932910335, 41485.0, 41596.0, 7481.102267727853, 7849.764507064783, 7988.680452781882, 8001.559914204717, 8006.9199308006755, 7377.7786666399825, 7475.140358858719, 7506.962465187667, 7651.655675494589, 7655.0, 10390.480475976208, 10476.319039262424, 10476.895231047707, 10488.475576221197, 10492.895071049306, 10224.233182511303, 10331.328463649914, 10337.37975721459, 10350.63772767957, 10355.482225888712, 7469.439792015566, 9926.45404502752, 10318.484075796181, 10349.430781307025, 10583.947080264626, 37953.62046379528, 38250.80874595637, 38256.42615360772, 38335.42496862549, 38437.423438648446, 2106.968395474069, 2759.7748213870227, 2778.659892029215, 2831.377097038648, 3160.4311223979676, 26428.0, 28809.0, 29178.41643167125, 29710.10869673913, 30109.247268818235, 3424.4692072728517, 3453.0, 3496.318217947501, 3505.071156143624, 3527.224010717638, 254773.0, 255176.7241163801, 255646.0, 256293.0, 257076.0, 46874.76562617233, 51502.48497515052, 51930.22104668433, 52214.477855221725, 52297.0, 48442.0, 48696.269555956686, 48723.512764872605, 48844.75577622089, 48856.26715599269, 12621.684457888443, 12684.04869634781, 12728.490860365482, 12754.170978886343, 12760.489580416679, 8867.467951922905, 9720.659776907774, 9791.608335666637, 9796.755081123029, 9797.118259356692, 13989.230592317317, 14022.22877741734, 14278.0, 14634.341454634567, 14903.850961490247, 5524.116141417354, 5552.91670624941, 5559.9444005560235, 5619.971900140473, 5648.717564121777, 833.2917020532499, 904.5432056811268, 905.6920646979927, 906.4198912695748, 956.1251454918811, 27132.457350852874, 28419.43161136807, 28755.856220718764, 28876.0, 28992.42015159685, 846.1665259719172, 914.0767824497173, 944.8157609266265, 999.5551979369111, 1018.7720958024258, 14441.35013924376, 15261.542153735183, 15269.694606107816, 15290.388384464497, 15528.301226444837, 475383.0, 481940.0, 483786.0, 698880.0, 706748.0, 49001.0, 49698.254526182136, 49750.0, 49777.25334119991, 49799.0, 1103.178132291432, 1128.1087940527057, 1173.9413029348498, 1207.6678913298913, 1234.5061975209987, 27987.160385188476, 29219.85390073036, 29257.561136583394, 30099.247518811328, 30100.54849177308, 42566.0, 42915.785421072695, 43015.56984430241, 43072.35391469068, 43193.3520997179, 330484.0, 333658.0, 334613.0, 334981.0, 335051.0, 31818.204544886565, 32213.35573288568, 32258.19354516062, 32605.18487037843, 32718.34563308768, 42095.0, 42740.0, 42785.358219626105, 43629.0, 43635.345469817345, 37339.62660373362, 37339.813300933325, 37438.8128059358, 37706.62293377031, 37741.4338784924, 36044.63955360413, 36905.44641830321, 36906.0, 37633.81183094067, 37736.81131594325, 36408.08979775527, 36410.0, 36821.81589092038, 37025.25949481049, 37192.628073718915, 32489.512657309686, 33234.54297380647, 33237.0, 33399.83300083484, 33749.0, 25130.452101175502, 25238.621420679072, 25405.34865233754, 25424.491510170064, 25822.741772582034], "value": 17840.111615422593, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.2976532053233, 357.15710922223553, 368.7326688151091, 379.6810679029616, 399.08808372868003, 13070.215787052783, 14847.703045939088, 17174.398896038656, 17327.047012414314, 17910.194041268143, 16439.2602332895, 17783.199756010978, 17945.192466339013, 18032.909835450806, 18038.18828152733, 5021.447640759514, 5199.350081239845, 5241.685498870061, 5245.921311180336, 5259.0, 3775.18833450808, 5153.6392452528335, 5764.711764411783, 6053.576249662526, 6100.1459795628425, 6095.542834287424, 6100.206973093492, 6117.755289788403, 6129.32577416486, 6132.570720049598, 4930.457649658535, 5224.92162617561, 5227.346581677271, 5303.098473259555, 5308.893822123575, 3395.8641654333796, 4947.406311242638, 5139.460356662571, 5237.816676416345, 5243.370795504526, 11641.534338626445, 11779.882201177965, 11882.58410955621, 12293.631191064282, 19487.31794387204, 1868.8504919606398, 2018.4651067467132, 2189.879556624385, 2310.2145270608075, 2374.3707917401903, 8906.064863189402, 8930.464172149692, 9082.954585227031, 9431.0, 9499.430034197901, 1751.789785225781, 1756.1833747307435, 1766.3287950578826, 1807.8644101692425, 1847.094923487495, 63932.57588941623, 69424.95862562116, 71975.20061998439, 72861.63569182174, 75409.0, 9679.612815487442, 9835.360701554519, 9952.900470995342, 9953.80092398148, 10145.289829711997, 13533.45866165362, 13635.113717608521, 13660.180389176681, 13665.795013074812, 13675.521356752466, 6535.8366040848405, 6598.109255250576, 6864.416524595365, 6877.4841886858685, 6956.234814170437, 6287.402696743802, 6313.3686631336495, 6433.067205255305, 6458.773942912069, 6459.741610335628, 4325.870223893288, 4342.6742994275555, 4404.0, 4405.568190338153, 4480.686351955397, 1658.4195531563855, 1753.7720096387388, 1754.3421217043608, 1795.371619933039, 1850.5188650950581, 847.3898792869124, 861.095849358184, 943.3820847345, 943.4056544376998, 945.4374647084974, 16208.189590520427, 17318.393856215225, 17496.125193740263, 17905.641887162183, 17977.640447190985, 829.1592664285278, 865.1305438034735, 870.1516021878617, 924.4499522783933, 926.4719110107175, 9682.75793105178, 9807.852882206773, 9958.75515560555, 9986.700398988041, 10163.44101074448, 358206.0, 366012.0, 366661.0, 367312.0, 372121.0, 74962.0, 75700.62149689216, 75888.24111758811, 78570.0, 79497.0, 849.1211595997988, 873.724776695339, 886.1138861138861, 889.644142343055, 894.1460904835866, 11907.5749644806, 15749.212539373215, 16208.270627822012, 16505.752413714043, 16823.41118060911, 27213.319667008484, 37303.25393492169, 38413.423798642485, 38580.614193857706, 38594.22811543697, 46331.30503042525, 48225.0, 48412.757936210095, 49000.0, 49244.75377623089, 7602.6198690066385, 7671.923280767121, 7724.845503089794, 7795.64919578609, 7978.960105199669, 10996.780064398827, 11917.52329906794, 12017.09871759604, 12033.879661203277, 12174.208676436003, 10372.22208334393, 10372.481375931324, 10604.681859544226, 10817.56729730802, 10855.457227138453, 9733.707988760065, 9887.703368898945, 9990.300678952404, 10275.383476991405, 10308.690739277834, 10490.475476226005, 10615.734606634896, 10689.198310126616, 10717.94641026821, 11031.282966607145, 7561.4706970513735, 7786.33816125613, 7804.609769511615, 7940.6029698515995, 8002.439829211899, 8733.606987685696, 10592.470376481298, 10677.679669609925, 10855.511501982272, 10956.890431095588], "value": 8109.380254212093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.9324533773308, 1409.1404243411519, 1462.7293950619137, 1673.0630846725842, 1676.6562854614797, 7552.093748750151, 7956.641951112199, 8032.598370081501, 8035.71874984375, 8044.477108987911, 6925.134358205224, 7282.70869165234, 7393.963030184842, 7425.443091768112, 8046.557439340834, 2203.4160947348923, 2230.4981379189717, 2232.263353093483, 2232.8995195216253, 2233.02863254484, 2137.8396620253466, 2149.376680762577, 2184.1045171479695, 2186.682930975008, 2194.2320187934174, 1858.8977606231651, 1921.7982111878262, 1926.5568919148604, 1933.3619905431244, 1935.235581945131, 2161.448830548208, 2224.12147201856, 2225.121077174515, 2226.7327920649627, 2237.6084185267514, 2185.6830759539935, 2195.9451013724783, 2208.0726095040122, 2218.9681797964026, 2222.4443889027743, 6927.376536111724, 7773.183815699326, 7969.72105976288, 8146.551939643317, 8168.4282100253, 4567.931481027754, 4715.457722361938, 4723.622110231173, 4725.196716558194, 4725.503822098664, 2710.4985577668194, 2758.2690586994463, 2802.1313392848283, 2805.9017934372405, 2809.0730059080456, 858.4291446188278, 863.3222920007827, 863.7581477186397, 1645.4405502129214, 1702.9659406811918, 10957.56169753209, 11089.27919685218, 11095.500702468327, 11136.276142050741, 11152.888471115266, 3929.410588411704, 3958.386450100239, 4028.4964379452567, 4108.958910410917, 4116.6912481564, 8732.214100730966, 8752.21230089295, 8752.56237188138, 8765.167309105625, 8774.692885748967, 4534.523874993144, 4537.636989040869, 4569.908601827945, 4572.039871626929, 4582.175208462442, 4178.561251068655, 4242.490901091889, 4244.384564238168, 4245.108527209259, 4246.893827654271, 3374.3251349730153, 3420.3159368126476, 3438.7764795288726, 3441.9655803442147, 3482.6169121396624, 995.9053889880595, 996.3573495095623, 1000.7998400319965, 1008.324422636835, 1011.2870426349406, 662.0797092042066, 679.8368391585984, 689.5276735436242, 689.9068625735462, 696.0763018107809, 4846.491118432585, 4942.085714142895, 5029.723365214875, 5040.697558146523, 5048.873778155576, 386.1928569290197, 392.04536952520704, 396.6806720589895, 399.31517447577545, 401.81315688205126, 3329.550510681027, 3443.5695538057744, 3464.2725027744455, 3485.0, 3513.4202856528896, 9677.080677335642, 10019.048190421898, 10024.14794742455, 10037.548310326052, 10072.446015469073, 9913.306068575275, 9937.354071985297, 9949.253805964581, 9963.452010139366, 9972.0, 6872.209695884937, 7186.389156921616, 7487.475876688688, 7582.696692132253, 7588.468807183444, 5425.83722488326, 5427.4301198373605, 5470.152126420411, 5574.5261652758345, 5596.272484576979, 9210.355275130676, 9303.209227215762, 9409.059094090537, 9440.622375104918, 9445.763855903657, 9039.95480022622, 9616.0, 9642.325037247325, 9658.468784216935, 9665.855012174683, 6911.516193866381, 6979.441644668516, 7011.474139439664, 7014.789556313319, 7014.824629384306, 7212.0, 7301.963490182515, 7312.488125831141, 7319.63401829917, 7323.890141647986, 6517.283098859027, 6683.264840867403, 6704.899426508704, 6759.797206083826, 6793.218779840382, 6479.643619600967, 6507.316731743288, 6539.149910511603, 6631.535792494479, 6835.726570937306, 6003.7898673546215, 6076.969615151896, 6083.939160608337, 6131.877362452815, 6136.6011209271255, 4308.418363520885, 4345.2395830728865, 4403.933940990823, 4441.689081764374, 4468.597826195658, 4394.560543945579, 4402.449693788277, 4416.889577760582, 4448.243798554318, 4477.126960242721], "value": 4018.5826504144975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.4435039527269, 305.47763324714737, 306.8128441650594, 308.60498561840825, 313.026487623491, 4922.409310882695, 4947.208446648537, 4963.801447942083, 4967.155583550801, 5000.274960130779, 4834.637402194832, 4913.410390753105, 4916.1888288432365, 4920.950790492103, 4926.768307923019, 3641.5083963664897, 3689.8524059037604, 3759.6653188118235, 3800.2589495048337, 3859.710521710883, 3769.5853456119803, 3798.8480460781675, 3814.370628846237, 3824.508441707736, 3848.6921046316365, 3628.8024951765988, 3799.9620003800023, 3802.1065049713297, 3817.9192340174673, 3825.387937929932, 3574.963260654407, 3760.6051364606597, 3765.6799172070405, 3776.8678096266635, 3844.4233364995193, 3403.4894765785084, 3407.2163402417336, 3408.5739282589675, 3413.300273443938, 3447.258839349552, 4731.905361892777, 4734.550217729345, 4748.0, 4749.3588365570895, 4771.28430735389, 4305.806238719265, 4368.432103826514, 4390.670699697535, 4405.163019026407, 4431.667624928111, 5096.0, 5108.390856880071, 5122.871928201787, 5124.974375128138, 5129.256257842592, 3704.629537046301, 3718.3121122592406, 3756.136088699587, 3763.849446022156, 3767.792771397572, 5136.0, 5269.446708095671, 5286.973565132188, 5370.624056316059, 5372.113601255788, 3778.8299526521373, 3784.2242340320718, 3784.924301513954, 3805.6765174959883, 3841.8079096045644, 3866.9806650966566, 3867.9226415471535, 3884.4561761353434, 3891.280113179071, 3895.7078219133678, 4341.413909122291, 4348.717333373321, 4353.25994580922, 4376.452943382077, 4383.408239887638, 4857.247126695368, 5086.72023038729, 5088.618353623419, 5092.898142037138, 5105.540501354896, 1285.884270415667, 1286.5625687266188, 1290.9612711618665, 1300.3368282175968, 1306.1183701001785, 800.4316934976116, 824.7938015496126, 830.3647709502236, 834.8831163637095, 845.3364109174391, 4228.703990720682, 4378.80295386702, 4485.394471746273, 4485.416895803524, 4489.304157855538, 5250.4749525047955, 5272.2355258487305, 5290.947090529123, 5297.152455607084, 5317.229001794718, 4461.107778444324, 4461.531539188403, 4462.330650402466, 4469.329600559942, 4471.821127154877, 2651.5757478803685, 2773.8751756171023, 2777.666679998413, 2780.6225677045836, 2797.1328888044773, 4825.396825396825, 4844.249141383091, 4864.829730959399, 4872.415310162733, 4889.315495830587, 5134.094306560669, 5346.278252435949, 5359.973200133974, 5381.9461805382225, 5383.7388296914605, 4872.829450969199, 4890.381117972745, 4894.804207831789, 4895.632827537949, 4899.951000489949, 5219.582433405394, 5245.213218017328, 5246.711430871186, 5252.711100889488, 5269.762860671202, 4972.502749725143, 5079.933214025096, 5105.030044291572, 5162.303089082926, 5177.223416487557, 4861.708297502161, 4899.3385892904, 4916.7541622919425, 4924.498028101463, 4924.704517728947, 3704.7962362070343, 3723.7579557328686, 3725.7950812705562, 3749.287635349384, 3761.097336639242, 5109.744512774272, 5343.65266257692, 5352.9732351338, 5361.865953351197, 5379.838604841861, 5103.234514822806, 5116.411612664591, 5117.667351622133, 5125.308083408692, 5213.869653258699, 5110.182370820651, 5115.769790359368, 5120.692758434506, 5122.410922743932, 5133.460986596503, 4872.366592342973, 4954.702717836942, 5031.144705400164, 5045.722485263199, 5060.696358218519, 4870.878228044186, 4963.354763880672, 5115.590752739847, 5122.8207012755865, 5147.716875571879, 4985.631824883297, 4997.650164488451, 4999.800007999639, 5006.299118123538, 5043.42000669913], "value": 3849.223368414231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.756922092721, 590.4007432456056, 600.3696119074973, 628.7296462521114, 633.9809805705828, 11289.20975531712, 12066.457009434575, 12406.81389779154, 12500.937495312523, 12505.124641275115, 11767.529298828036, 11806.527738890456, 11950.940245298762, 11954.402279885993, 11973.521059157645, 606.702715669321, 620.5780069552702, 745.1803016681633, 765.885117232414, 770.8959290495767, 744.4193529047325, 767.758156180804, 768.9538627682357, 774.1987043410044, 775.1899265267781, 809.2312303311877, 809.4778867630375, 814.2752949874616, 814.3241109878786, 814.9592520373988, 640.1805688718448, 657.3984803904422, 806.371030596133, 807.7657479330987, 809.6599428240124, 483.6445212768612, 484.08991096737907, 569.4760820045568, 584.2200662116038, 585.166138252992, 12278.386080696018, 12287.877121228852, 12308.199967002205, 12333.444994975158, 12346.5061397544, 12742.171758835739, 13327.133736307109, 13384.79922801149, 13395.196288222738, 13534.390952407068, 11937.701557461047, 12113.869178554853, 12257.877421225763, 12482.875171248263, 12895.484180632764, 4179.6029377209115, 4204.915901681949, 4253.1706317268145, 4272.5086615039045, 4345.41336919515, 60372.094418584194, 60450.395496045356, 60972.39027609756, 61018.38981610171, 61282.69358653223, 9935.751606209902, 10136.2904596679, 10176.389416635026, 10214.438205898598, 10269.127124194527, 16330.265138068815, 16417.91791041037, 16467.917660411622, 16547.83452165487, 16559.917200414162, 6465.515086368447, 6489.383508566679, 6501.934980650228, 6511.706973186218, 6652.0354548590185, 3446.4141096013736, 3450.0857272822723, 3546.7517273790586, 3554.893353199408, 3569.1077230692326, 2704.229294651015, 2797.3006748312923, 2798.1885253276323, 2798.426322603902, 2803.5794630805544, 480.47628085386395, 482.6404328775015, 490.1642699197859, 490.399260905388, 491.13069866336383, 516.4886762105489, 527.1749711701175, 528.3738769558086, 533.8937890023818, 538.6256551696533, 7169.139703235645, 7386.519876208029, 7629.618519074135, 8002.599870006593, 8032.39757018226, 496.6895690193629, 521.4941506738458, 538.284082170712, 574.5633318677751, 600.1837500998638, 3890.435886796399, 3943.2704949583845, 4208.852690155891, 4285.97857010713, 4307.203167413976, 93423.0, 93440.53279733694, 93582.51339892793, 94586.0, 96143.51928240454, 82509.58745206236, 85988.5700571493, 86286.56856715676, 86481.0, 86657.0, 67663.66168169127, 70655.0, 71785.64107179535, 73856.89214661784, 73912.26087739161, 8024.518528888286, 8036.959815200887, 8056.509545734058, 8073.071596766212, 8357.206065423776, 43289.35065974076, 43441.0, 45059.54940450685, 45201.0, 45529.0, 41420.378694320214, 41581.58418415898, 42908.78545607252, 57537.0, 62925.68537157285, 9659.613615455302, 9767.218622510325, 9885.604575816888, 9974.351667141902, 10001.449920254456, 11493.54025838957, 11565.36390498497, 11794.76410471803, 11987.0, 12102.515899363927, 10490.737731556772, 10548.314359566604, 10578.947105264424, 10993.945030274797, 11300.208985370946, 11008.834867477155, 11069.446527673745, 11424.714382140513, 11576.594819181288, 11596.594119205787, 10708.518116684612, 10990.395528245703, 11046.668599942015, 11591.826122608, 11617.535298587962, 9096.727098187064, 9268.860967085364, 9323.300752443458, 9363.320176248428, 9442.244620430212, 9230.261579073793, 9321.813563728552, 9499.430034197694, 9753.60985560598, 9813.046778252638], "value": 6287.333487206326, "unit": "score"}], "name": "dromaeo_css", "value": 6331.164312227557, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 114.0, 115.0, 114.0, 113.0, 116.0, 112.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 124.0, 125.0, 185.0, 122.0, 123.0, 142.0, 122.0, 158.0, 121.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 163.0, 162.0, 161.0, 161.0, 163.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 107.0, 79.0, 77.0, 76.0, 76.0, 97.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 116.0, 86.0, 86.0, 85.0, 121.0, 85.0, 85.0, 130.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 148.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 64.0, 64.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 66.0, 65.0, 64.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 78.0, 82.0, 78.0, 79.0, 85.0, 79.0, 80.0, 78.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 127.0, 130.0, 139.0, 127.0, 136.0, 142.0, 162.0, 132.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 162.0, 162.0, 163.0, 169.0, 165.0, 163.0, 163.0, 164.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 72.0, 67.0, 68.0, 66.0, 66.0, 65.0, 69.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1525.3000000000002, "alertThreshold": 2.0}]} 19:04:55 INFO - Return code: 0 19:04:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:04:55 INFO - # TBPL SUCCESS # 19:04:55 INFO - Running post-action listener: _resource_record_post_action 19:04:55 INFO - ##### 19:04:55 INFO - ##### Finished run-tests step (success) 19:04:55 INFO - ##### 19:04:55 INFO - Running post-run listener: _resource_record_post_run 19:04:55 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 534896640; Read time: 8; Write time: 412064 19:04:55 INFO - TinderboxPrint: CPU usage
13.0% 19:04:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 19:04:55 INFO - TinderboxPrint: I/O write bytes / time
534,896,640 / 412,064 19:04:55 INFO - TinderboxPrint: CPU idle
8,327.5 (87.0%) 19:04:55 INFO - TinderboxPrint: CPU user
1,203.1 (12.6%) 19:04:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:04:55 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 245981184; Read time: 0; Write time: 254040 19:04:55 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 288530432; Read time: 0; Write time: 158000 19:04:56 INFO - Running post-run listener: _upload_blobber_files 19:04:56 INFO - Blob upload gear active. 19:04:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:04:56 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 19:04:56 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:04:56 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:04:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:04:59 INFO - (blobuploader) - INFO - Done attempting. 19:04:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:04:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:04:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:00 INFO - (blobuploader) - INFO - Done attempting. 19:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:05:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:01 INFO - (blobuploader) - INFO - Done attempting. 19:05:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:05:01 INFO - Return code: 0 19:05:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:05:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:05:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/593d9fa87305555646e46714544f56c75d2b93736d59081bed3fa6ebf26f49b4a8dbcf977e25f002a9df9f60e1cc0dc31812ef419c087cff9c6476edcfb9ca4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9b5a4e5cd4afb1963d630f06a5f6a133bdcb97c91dc7a550d39a70153d5ee78b806e93d291d746c42015d0e2489c8c35bc2b7eeee31fb7b71fe3877cad3a4d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef205af3822d7b7908c403b5afced663f2d761a29466679860ce427b14eccd01129a84cc35792bcebc486fe6e05eec41d047fbf14c8c16c9ae0ec2c9678486e2"} 19:05:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:05:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:05:01 INFO - Contents: 19:05:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/593d9fa87305555646e46714544f56c75d2b93736d59081bed3fa6ebf26f49b4a8dbcf977e25f002a9df9f60e1cc0dc31812ef419c087cff9c6476edcfb9ca4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9b5a4e5cd4afb1963d630f06a5f6a133bdcb97c91dc7a550d39a70153d5ee78b806e93d291d746c42015d0e2489c8c35bc2b7eeee31fb7b71fe3877cad3a4d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef205af3822d7b7908c403b5afced663f2d761a29466679860ce427b14eccd01129a84cc35792bcebc486fe6e05eec41d047fbf14c8c16c9ae0ec2c9678486e2"} 19:05:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:05:01 INFO - Copying logs to upload dir... 19:05:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:05:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.849880 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-07-07 19:05:01.198260) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:05:01.201902) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/593d9fa87305555646e46714544f56c75d2b93736d59081bed3fa6ebf26f49b4a8dbcf977e25f002a9df9f60e1cc0dc31812ef419c087cff9c6476edcfb9ca4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9b5a4e5cd4afb1963d630f06a5f6a133bdcb97c91dc7a550d39a70153d5ee78b806e93d291d746c42015d0e2489c8c35bc2b7eeee31fb7b71fe3877cad3a4d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef205af3822d7b7908c403b5afced663f2d761a29466679860ce427b14eccd01129a84cc35792bcebc486fe6e05eec41d047fbf14c8c16c9ae0ec2c9678486e2"} build_url:https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/T_wGXrZ-TBmKWnT_tq97Ww/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/593d9fa87305555646e46714544f56c75d2b93736d59081bed3fa6ebf26f49b4a8dbcf977e25f002a9df9f60e1cc0dc31812ef419c087cff9c6476edcfb9ca4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e9b5a4e5cd4afb1963d630f06a5f6a133bdcb97c91dc7a550d39a70153d5ee78b806e93d291d746c42015d0e2489c8c35bc2b7eeee31fb7b71fe3877cad3a4d9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef205af3822d7b7908c403b5afced663f2d761a29466679860ce427b14eccd01129a84cc35792bcebc486fe6e05eec41d047fbf14c8c16c9ae0ec2c9678486e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 19:05:01.229757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:05:01.230448) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1467941917.58167-731414835 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 19:05:01.313963) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:05:01.314663) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 19:05:01.315214) ========= ========= Total master_lag: 0.22 =========