builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1472105628.34 results: success (0) buildid: 20160824202526 builduid: 2549f46e03e943c2ae3c383c9d187b6f revision: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.337102) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.337589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.337862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.383559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.383923) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-24 23:13:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-08-24 23:13:49 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.504520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.504921) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019659 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.554699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:48.558076) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 --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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-24 23:13:49,184 truncating revision to first 12 chars 2016-08-24 23:13:49,184 Setting DEBUG logging. 2016-08-24 23:13:49,184 attempt 1/10 2016-08-24 23:13:49,184 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/30b1e0880c0f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 23:13:49,445 unpacking tar archive at: mozilla-aurora-30b1e0880c0f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587612 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:49.159340) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:49.159684) ========= script_repo_revision: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:49.160117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:49.160415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 23:13:49.171277) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-24 23:13:49.171646) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:13:49 INFO - MultiFileLogger online at 20160824 23:13:49 in /builds/slave/test 23:13:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:13:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:13:49 INFO - {'append_to_log': False, 23:13:49 INFO - 'base_work_dir': '/builds/slave/test', 23:13:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:13:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:13:49 INFO - 'branch': 'Mozilla-Aurora', 23:13:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:13:49 INFO - 'config_files': ('talos/linux_config.py',), 23:13:49 INFO - 'default_actions': ('clobber', 23:13:49 INFO - 'read-buildbot-config', 23:13:49 INFO - 'download-and-extract', 23:13:49 INFO - 'populate-webroot', 23:13:49 INFO - 'create-virtualenv', 23:13:49 INFO - 'install', 23:13:49 INFO - 'run-tests'), 23:13:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:13:49 INFO - 'download_minidump_stackwalk': True, 23:13:49 INFO - 'download_symbols': 'ondemand', 23:13:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:13:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:13:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:13:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:13:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:13:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:13:49 INFO - 'installer_path': 'installer.exe', 23:13:49 INFO - 'log_level': 'info', 23:13:49 INFO - 'log_name': 'talos', 23:13:49 INFO - 'log_to_console': True, 23:13:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:13:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:13:49 INFO - 'opt_config_files': (), 23:13:49 INFO - 'pip_index': False, 23:13:49 INFO - 'sps_profile': False, 23:13:49 INFO - 'sps_profile_interval': 0, 23:13:49 INFO - 'suite': 'dromaeojs', 23:13:49 INFO - 'system_bits': '32', 23:13:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:13:49 INFO - 'title': 'talos-linux64-ix-061', 23:13:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:13:49 INFO - 'use_talos_json': True, 23:13:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:13:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:13:49 INFO - 'work_dir': 'build'} 23:13:49 INFO - [mozharness: 2016-08-25 06:13:49.892669Z] Running clobber step. 23:13:49 INFO - Running pre-action listener: _resource_record_pre_action 23:13:49 INFO - Running main action method: clobber 23:13:49 INFO - rmtree: /builds/slave/test/build 23:13:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:13:50 INFO - Running post-action listener: _resource_record_post_action 23:13:50 INFO - [mozharness: 2016-08-25 06:13:50.357985Z] Finished clobber step (success) 23:13:50 INFO - [mozharness: 2016-08-25 06:13:50.358086Z] Running read-buildbot-config step. 23:13:50 INFO - Running pre-action listener: _resource_record_pre_action 23:13:50 INFO - Running main action method: read_buildbot_config 23:13:50 INFO - Using buildbot properties: 23:13:50 INFO - { 23:13:50 INFO - "project": "", 23:13:50 INFO - "product": "firefox", 23:13:50 INFO - "script_repo_revision": "production", 23:13:50 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:13:50 INFO - "repository": "", 23:13:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:13:50 INFO - "buildid": "20160824202526", 23:13:50 INFO - "pgo_build": "True", 23:13:50 INFO - "basedir": "/builds/slave/test", 23:13:50 INFO - "buildnumber": 490, 23:13:50 INFO - "slavename": "talos-linux64-ix-061", 23:13:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:13:50 INFO - "platform": "ubuntu64_hw", 23:13:50 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:13:50 INFO - "branch": "mozilla-aurora", 23:13:50 INFO - "repo_path": "releases/mozilla-aurora", 23:13:50 INFO - "revision": "30b1e0880c0f629c19f19b83576e6f5bd4f72a15", 23:13:50 INFO - "stage_platform": "linux64-pgo", 23:13:50 INFO - "builduid": "2549f46e03e943c2ae3c383c9d187b6f", 23:13:50 INFO - "slavebuilddir": "test" 23:13:50 INFO - } 23:13:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 23:13:50 INFO - Running post-action listener: _resource_record_post_action 23:13:50 INFO - [mozharness: 2016-08-25 06:13:50.360568Z] Finished read-buildbot-config step (success) 23:13:50 INFO - [mozharness: 2016-08-25 06:13:50.360657Z] Running download-and-extract step. 23:13:50 INFO - Running pre-action listener: _resource_record_pre_action 23:13:50 INFO - Running main action method: download_and_extract 23:13:50 INFO - mkdir: /builds/slave/test/build/tests 23:13: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')]} 23:13:50 INFO - https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:13:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:13:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:13:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:13:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:13:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:13:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:13:50 INFO - retry: Failed, sleeping 30 seconds before retrying 23:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 23:14:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:14:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:14:20 INFO - retry: Failed, sleeping 60 seconds before retrying 23:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 23:15:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:15:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 23:15:20 INFO - Caught exception: HTTP Error 404: Not Found 23:15:20 INFO - Caught exception: HTTP Error 404: Not Found 23:15:20 INFO - Caught exception: HTTP Error 404: Not Found 23:15:20 INFO - trying https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - Downloading https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:15:21 INFO - Downloaded 1361 bytes. 23:15:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 23:15:21 INFO - Using the following test package requirements: 23:15:21 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:15:21 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:15:21 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:15:21 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'jsshell-linux-x86_64.zip'], 23:15:21 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:15:21 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 23:15:21 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:15:21 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:15:21 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:15:21 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 23:15:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:15:21 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:15:21 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')]} 23:15:21 INFO - https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:15:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:15:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:15:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:15:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:15:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:15:21 INFO - retry: Failed, sleeping 30 seconds before retrying 23:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 23:15:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:15:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:15:51 INFO - retry: Failed, sleeping 60 seconds before retrying 23:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 23:16:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:16:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 23:16:51 INFO - Caught exception: HTTP Error 404: Not Found 23:16:51 INFO - Caught exception: HTTP Error 404: Not Found 23:16:51 INFO - Caught exception: HTTP Error 404: Not Found 23:16:51 INFO - trying https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - Downloading https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:16:51 INFO - Downloaded 21770487 bytes. 23:16:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:16:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:16:53 INFO - Return code: 0 23:16:53 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:16:53 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')]} 23:16:53 INFO - https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:16:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:16:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:16:53 INFO - retry: Failed, sleeping 30 seconds before retrying 23:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 23:17:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:17:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:17:23 INFO - retry: Failed, sleeping 60 seconds before retrying 23:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 23:18:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:18:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:18:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 23:18:23 INFO - Caught exception: HTTP Error 404: Not Found 23:18:23 INFO - Caught exception: HTTP Error 404: Not Found 23:18:23 INFO - Caught exception: HTTP Error 404: Not Found 23:18:23 INFO - trying https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 23:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:18:24 INFO - Downloaded 21770487 bytes. 23:18:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:18:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:18:25 INFO - Return code: 0 23:18: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')]} 23:18:25 INFO - https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:18:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:18:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:18:25 INFO - retry: Failed, sleeping 30 seconds before retrying 23:18:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 23:18:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:18:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:18:55 INFO - retry: Failed, sleeping 60 seconds before retrying 23:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 23:19:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:19:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:19:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 23:19:55 INFO - Caught exception: HTTP Error 404: Not Found 23:19:55 INFO - Caught exception: HTTP Error 404: Not Found 23:19:55 INFO - Caught exception: HTTP Error 404: Not Found 23:19:55 INFO - trying https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:19:55 INFO - Downloading https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 23:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:19:56 INFO - Downloaded 14413723 bytes. 23:19:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:19:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:19:56 INFO - Return code: 0 23:19:56 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')]} 23:19:56 INFO - https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:19:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:19:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:19:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:19:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:19:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 23:19:56 INFO - retry: Failed, sleeping 30 seconds before retrying 23:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 23:20:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:20:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 23:20:26 INFO - retry: Failed, sleeping 60 seconds before retrying 23:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 23:21:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:21:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 23:21:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 23:21:26 INFO - Caught exception: HTTP Error 404: Not Found 23:21:26 INFO - Caught exception: HTTP Error 404: Not Found 23:21:26 INFO - Caught exception: HTTP Error 404: Not Found 23:21:26 INFO - trying https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:21:27 INFO - Downloaded 63740804 bytes. 23:21:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:21:27 INFO - mkdir: /builds/slave/test/properties 23:21:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:21:27 INFO - Writing to file /builds/slave/test/properties/build_url 23:21:27 INFO - Contents: 23:21:27 INFO - build_url:https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 23:21:28 INFO - Running post-action listener: _resource_record_post_action 23:21:28 INFO - Running post-action listener: set_extra_try_arguments 23:21:28 INFO - [mozharness: 2016-08-25 06:21:28.570723Z] Finished download-and-extract step (success) 23:21:28 INFO - [mozharness: 2016-08-25 06:21:28.570955Z] Running populate-webroot step. 23:21:28 INFO - Running pre-action listener: _resource_record_pre_action 23:21:28 INFO - Running main action method: populate_webroot 23:21:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:21:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:21:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:21:28 INFO - '--tppagecycles', 23:21:28 INFO - '7'], 23:21:28 INFO - 'tests': ['tsvgm']}}, 23:21:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:21:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:21:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:21:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:21:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['damp', 'tps']}, 23:21:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'tests': ['damp', 'tps']}, 23:21:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['dromaeo_dom']}, 23:21:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:21:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['basic_compositor_video']}, 23:21:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:21:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'other-e10s': {'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['a11yr', 23:21:28 INFO - 'ts_paint', 23:21:28 INFO - 'tpaint', 23:21:28 INFO - 'sessionrestore', 23:21:28 INFO - 'sessionrestore_no_auto_restore', 23:21:28 INFO - 'tabpaint']}, 23:21:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['tsvgx', 23:21:28 INFO - 'tsvgr_opacity', 23:21:28 INFO - 'tart', 23:21:28 INFO - 'tscrollx', 23:21:28 INFO - 'cart']}, 23:21:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:21:28 INFO - 'tsvgr_opacity', 23:21:28 INFO - 'tart', 23:21:28 INFO - 'tscrollx', 23:21:28 INFO - 'cart']}, 23:21:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'talos_options': ['--disable-e10s'], 23:21:28 INFO - 'tests': ['tp5o']}, 23:21:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'tests': ['tp5o']}, 23:21:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'talos_options': ['--disable-e10s', 23:21:28 INFO - '--xperf_path', 23:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:28 INFO - 'tests': ['tp5n']}, 23:21:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:28 INFO - 'talos_options': ['--xperf_path', 23:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:28 INFO - 'tests': ['tp5n']}}, 23:21:28 INFO - 'talos.zip': {'path': '', 23:21:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:21:28 INFO - Running post-action listener: _resource_record_post_action 23:21:28 INFO - [mozharness: 2016-08-25 06:21:28.589845Z] Finished populate-webroot step (success) 23:21:28 INFO - [mozharness: 2016-08-25 06:21:28.589933Z] Running create-virtualenv step. 23:21:28 INFO - Running pre-action listener: _resource_record_pre_action 23:21:28 INFO - Running main action method: create_virtualenv 23:21:28 INFO - Creating virtualenv /builds/slave/test/build/venv 23:21:28 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 23:21:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:21:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:21:28 INFO - Using real prefix '/usr' 23:21:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:21:29 INFO - Installing distribute.............................................................................................................................................................................................done. 23:21:31 INFO - Installing pip.................done. 23:21:31 INFO - Return code: 0 23:21:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:21:31 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')]} 23:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:31 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')]} 23:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:31 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:31 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 23:21:31 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 23:21:31 INFO - Using env: {'DISPLAY': ':0', 23:21:31 INFO - 'HOME': '/home/cltbld', 23:21:31 INFO - 'LANG': 'en_US.UTF-8', 23:21:31 INFO - 'LANGUAGE': 'en_US:en', 23:21:31 INFO - 'LOGNAME': 'cltbld', 23:21:31 INFO - 'MAIL': '/var/mail/cltbld', 23:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:31 INFO - 'MOZ_NO_REMOTE': '1', 23:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:31 INFO - 'NO_EM_RESTART': '1', 23:21:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:31 INFO - 'PWD': '/builds/slave/test', 23:21:31 INFO - 'SHELL': '/bin/bash', 23:21:31 INFO - 'SHLVL': '1', 23:21:31 INFO - 'TERM': 'linux', 23:21:31 INFO - 'TMOUT': '86400', 23:21:31 INFO - 'USER': 'cltbld', 23:21:31 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:31 INFO - '_': '/tools/buildbot/bin/python'} 23:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:31 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 23:21:31 INFO - Cleaning up... 23:21:31 INFO - Return code: 0 23:21:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:21:31 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')]} 23:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:31 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')]} 23:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:31 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:31 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 23:21:31 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 23:21:31 INFO - Using env: {'DISPLAY': ':0', 23:21:31 INFO - 'HOME': '/home/cltbld', 23:21:31 INFO - 'LANG': 'en_US.UTF-8', 23:21:31 INFO - 'LANGUAGE': 'en_US:en', 23:21:31 INFO - 'LOGNAME': 'cltbld', 23:21:31 INFO - 'MAIL': '/var/mail/cltbld', 23:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:31 INFO - 'MOZ_NO_REMOTE': '1', 23:21:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:31 INFO - 'NO_EM_RESTART': '1', 23:21:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:31 INFO - 'PWD': '/builds/slave/test', 23:21:31 INFO - 'SHELL': '/bin/bash', 23:21:31 INFO - 'SHLVL': '1', 23:21:31 INFO - 'TERM': 'linux', 23:21:31 INFO - 'TMOUT': '86400', 23:21:31 INFO - 'USER': 'cltbld', 23:21:31 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:31 INFO - '_': '/tools/buildbot/bin/python'} 23:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:31 INFO - Downloading/unpacking psutil>=3.1.1 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:31 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 23:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:21:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:33 INFO - Installing collected packages: psutil 23:21:33 INFO - Running setup.py install for psutil 23:21:33 INFO - building 'psutil._psutil_linux' extension 23:21:33 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 23:21:33 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 23:21:33 INFO - building 'psutil._psutil_posix' extension 23:21:33 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 23:21:33 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 23:21:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:34 INFO - Successfully installed psutil 23:21:34 INFO - Cleaning up... 23:21:34 INFO - Return code: 0 23:21:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:21:34 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')]} 23:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:34 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')]} 23:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:34 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:34 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 23:21:34 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 23:21:34 INFO - Using env: {'DISPLAY': ':0', 23:21:34 INFO - 'HOME': '/home/cltbld', 23:21:34 INFO - 'LANG': 'en_US.UTF-8', 23:21:34 INFO - 'LANGUAGE': 'en_US:en', 23:21:34 INFO - 'LOGNAME': 'cltbld', 23:21:34 INFO - 'MAIL': '/var/mail/cltbld', 23:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:34 INFO - 'MOZ_NO_REMOTE': '1', 23:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:34 INFO - 'NO_EM_RESTART': '1', 23:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:34 INFO - 'PWD': '/builds/slave/test', 23:21:34 INFO - 'SHELL': '/bin/bash', 23:21:34 INFO - 'SHLVL': '1', 23:21:34 INFO - 'TERM': 'linux', 23:21:34 INFO - 'TMOUT': '86400', 23:21:34 INFO - 'USER': 'cltbld', 23:21:34 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:34 INFO - '_': '/tools/buildbot/bin/python'} 23:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:21:34 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 23:21:34 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 23:21:34 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 23:21:34 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 23:21:34 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 23:21:34 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 23:21:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:21:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:21:37 INFO - Installing collected packages: mozsystemmonitor 23:21:37 INFO - Running setup.py install for mozsystemmonitor 23:21:37 INFO - Successfully installed mozsystemmonitor 23:21:37 INFO - Cleaning up... 23:21:37 INFO - Return code: 0 23:21:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:21:37 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')]} 23:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:37 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')]} 23:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:37 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:37 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 23:21:37 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 23:21:37 INFO - Using env: {'DISPLAY': ':0', 23:21:37 INFO - 'HOME': '/home/cltbld', 23:21:37 INFO - 'LANG': 'en_US.UTF-8', 23:21:37 INFO - 'LANGUAGE': 'en_US:en', 23:21:37 INFO - 'LOGNAME': 'cltbld', 23:21:37 INFO - 'MAIL': '/var/mail/cltbld', 23:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:37 INFO - 'MOZ_NO_REMOTE': '1', 23:21:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:37 INFO - 'NO_EM_RESTART': '1', 23:21:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:37 INFO - 'PWD': '/builds/slave/test', 23:21:37 INFO - 'SHELL': '/bin/bash', 23:21:37 INFO - 'SHLVL': '1', 23:21:37 INFO - 'TERM': 'linux', 23:21:37 INFO - 'TMOUT': '86400', 23:21:37 INFO - 'USER': 'cltbld', 23:21:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:37 INFO - '_': '/tools/buildbot/bin/python'} 23:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:37 INFO - Downloading/unpacking jsonschema==2.5.1 23:21:37 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 23:21:37 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 23:21:37 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 23:21:37 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 23:21:37 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 23:21:37 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 23:21:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:21:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:21:39 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 23:21:39 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 23:21:39 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 23:21:39 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 23:21:39 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 23:21:39 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 23:21:39 INFO - Downloading functools32-3.2.3-2.tar.gz 23:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:21:39 INFO - warning: no files found matching '*.txt' 23:21:39 INFO - no previously-included directories found matching 'build' 23:21:39 INFO - no previously-included directories found matching 'dist' 23:21:39 INFO - no previously-included directories found matching '.git*' 23:21:39 INFO - Installing collected packages: jsonschema, functools32 23:21:39 INFO - Running setup.py install for functools32 23:21:39 INFO - warning: no files found matching '*.txt' 23:21:39 INFO - no previously-included directories found matching 'build' 23:21:39 INFO - no previously-included directories found matching 'dist' 23:21:39 INFO - no previously-included directories found matching '.git*' 23:21:39 INFO - Successfully installed jsonschema functools32 23:21:39 INFO - Cleaning up... 23:21:39 INFO - Return code: 0 23:21:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:21:39 INFO - Using env: {'DISPLAY': ':0', 23:21:39 INFO - 'HOME': '/home/cltbld', 23:21:39 INFO - 'LANG': 'en_US.UTF-8', 23:21:39 INFO - 'LANGUAGE': 'en_US:en', 23:21:39 INFO - 'LOGNAME': 'cltbld', 23:21:39 INFO - 'MAIL': '/var/mail/cltbld', 23:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:39 INFO - 'MOZ_NO_REMOTE': '1', 23:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:39 INFO - 'NO_EM_RESTART': '1', 23:21:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:39 INFO - 'PWD': '/builds/slave/test', 23:21:39 INFO - 'SHELL': '/bin/bash', 23:21:39 INFO - 'SHLVL': '1', 23:21:39 INFO - 'TERM': 'linux', 23:21:39 INFO - 'TMOUT': '86400', 23:21:39 INFO - 'USER': 'cltbld', 23:21:39 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:39 INFO - '_': '/tools/buildbot/bin/python'} 23:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:21:40 INFO - Cleaning up... 23:21:40 INFO - Return code: 0 23:21:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:21:40 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')]} 23:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:40 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')]} 23:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:40 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:40 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 23:21:40 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 23:21:40 INFO - Using env: {'DISPLAY': ':0', 23:21:40 INFO - 'HOME': '/home/cltbld', 23:21:40 INFO - 'LANG': 'en_US.UTF-8', 23:21:40 INFO - 'LANGUAGE': 'en_US:en', 23:21:40 INFO - 'LOGNAME': 'cltbld', 23:21:40 INFO - 'MAIL': '/var/mail/cltbld', 23:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:40 INFO - 'MOZ_NO_REMOTE': '1', 23:21:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:40 INFO - 'NO_EM_RESTART': '1', 23:21:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:40 INFO - 'PWD': '/builds/slave/test', 23:21:40 INFO - 'SHELL': '/bin/bash', 23:21:40 INFO - 'SHLVL': '1', 23:21:40 INFO - 'TERM': 'linux', 23:21:40 INFO - 'TMOUT': '86400', 23:21:40 INFO - 'USER': 'cltbld', 23:21:40 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:40 INFO - '_': '/tools/buildbot/bin/python'} 23:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:40 INFO - Downloading/unpacking blobuploader==1.2.4 23:21:40 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 23:21:40 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 23:21:40 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 23:21:40 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 23:21:40 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 23:21:40 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 23:21:42 INFO - Downloading blobuploader-1.2.4.tar.gz 23:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:21:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:21:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:42 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 23:21:43 INFO - Downloading docopt-0.6.1.tar.gz 23:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:21:43 INFO - Installing collected packages: blobuploader, requests, docopt 23:21:43 INFO - Running setup.py install for blobuploader 23:21:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:21:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:21:43 INFO - Running setup.py install for requests 23:21:43 INFO - Running setup.py install for docopt 23:21:43 INFO - Successfully installed blobuploader requests docopt 23:21:43 INFO - Cleaning up... 23:21:43 INFO - Return code: 0 23:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21: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')]} 23:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:43 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:43 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 23:21:43 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 23:21:43 INFO - Using env: {'DISPLAY': ':0', 23:21:43 INFO - 'HOME': '/home/cltbld', 23:21:43 INFO - 'LANG': 'en_US.UTF-8', 23:21:43 INFO - 'LANGUAGE': 'en_US:en', 23:21:43 INFO - 'LOGNAME': 'cltbld', 23:21:43 INFO - 'MAIL': '/var/mail/cltbld', 23:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:43 INFO - 'MOZ_NO_REMOTE': '1', 23:21:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:43 INFO - 'NO_EM_RESTART': '1', 23:21:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:43 INFO - 'PWD': '/builds/slave/test', 23:21:43 INFO - 'SHELL': '/bin/bash', 23:21:43 INFO - 'SHLVL': '1', 23:21:43 INFO - 'TERM': 'linux', 23:21:43 INFO - 'TMOUT': '86400', 23:21:43 INFO - 'USER': 'cltbld', 23:21:43 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:43 INFO - '_': '/tools/buildbot/bin/python'} 23:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:21:43 INFO - Running setup.py (path:/tmp/pip-t6DguU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:21:44 INFO - Running setup.py (path:/tmp/pip-jw8lfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:21:44 INFO - Running setup.py (path:/tmp/pip-xTN9xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:21:44 INFO - Running setup.py (path:/tmp/pip-IRvJ9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:21:44 INFO - Running setup.py (path:/tmp/pip-qbF1uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:21:44 INFO - Running setup.py (path:/tmp/pip-ryu5bY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:21:44 INFO - Running setup.py (path:/tmp/pip-7tfPgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:21:44 INFO - Running setup.py (path:/tmp/pip-3OfpBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:21:44 INFO - Running setup.py (path:/tmp/pip-Ma8S_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:21:44 INFO - Running setup.py (path:/tmp/pip-HtKCeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:21:44 INFO - Running setup.py (path:/tmp/pip-TzbDLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:21:44 INFO - Running setup.py (path:/tmp/pip-_XA_Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:21:44 INFO - Running setup.py (path:/tmp/pip-_GY2DP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:21:44 INFO - Running setup.py (path:/tmp/pip-l3fSpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:44 INFO - Running setup.py (path:/tmp/pip-xY9uIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:21:45 INFO - Running setup.py (path:/tmp/pip-Y3uvfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:21:45 INFO - Running setup.py (path:/tmp/pip-sJbad_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:21:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:21:45 INFO - Running setup.py install for manifestparser 23:21:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Running setup.py install for mozcrash 23:21:45 INFO - Running setup.py install for mozdebug 23:21:45 INFO - Running setup.py install for mozdevice 23:21:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Running setup.py install for mozfile 23:21:45 INFO - Running setup.py install for mozhttpd 23:21:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Running setup.py install for mozinfo 23:21:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Running setup.py install for mozInstall 23:21:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:21:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Running setup.py install for mozleak 23:21:46 INFO - Running setup.py install for mozlog 23:21:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Running setup.py install for moznetwork 23:21:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Running setup.py install for mozprocess 23:21:46 INFO - Running setup.py install for mozprofile 23:21:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Running setup.py install for mozrunner 23:21:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Running setup.py install for mozscreenshot 23:21:46 INFO - Running setup.py install for moztest 23:21:46 INFO - Running setup.py install for mozversion 23:21:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:21:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:21:46 INFO - Cleaning up... 23:21:46 INFO - Return code: 0 23:21:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:46 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:46 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 23:21:46 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 23:21:46 INFO - Using env: {'DISPLAY': ':0', 23:21:46 INFO - 'HOME': '/home/cltbld', 23:21:46 INFO - 'LANG': 'en_US.UTF-8', 23:21:46 INFO - 'LANGUAGE': 'en_US:en', 23:21:46 INFO - 'LOGNAME': 'cltbld', 23:21:46 INFO - 'MAIL': '/var/mail/cltbld', 23:21:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:46 INFO - 'MOZ_NO_REMOTE': '1', 23:21:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:46 INFO - 'NO_EM_RESTART': '1', 23:21:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:46 INFO - 'PWD': '/builds/slave/test', 23:21:46 INFO - 'SHELL': '/bin/bash', 23:21:46 INFO - 'SHLVL': '1', 23:21:46 INFO - 'TERM': 'linux', 23:21:46 INFO - 'TMOUT': '86400', 23:21:46 INFO - 'USER': 'cltbld', 23:21:46 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:46 INFO - '_': '/tools/buildbot/bin/python'} 23:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:21:47 INFO - Running setup.py (path:/tmp/pip-DlIh6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:21:47 INFO - Running setup.py (path:/tmp/pip-ziB4JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:21:47 INFO - Running setup.py (path:/tmp/pip-OZeryS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:21:47 INFO - Running setup.py (path:/tmp/pip-XjcWwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:21:47 INFO - Running setup.py (path:/tmp/pip-aoLrWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:21:47 INFO - Running setup.py (path:/tmp/pip-gYUTA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:21:47 INFO - Running setup.py (path:/tmp/pip-xFS9gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:21:47 INFO - Running setup.py (path:/tmp/pip-14kxXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:21:47 INFO - Running setup.py (path:/tmp/pip-WO7SpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:21:47 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)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:21:47 INFO - Running setup.py (path:/tmp/pip-CGoMZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:21:47 INFO - Running setup.py (path:/tmp/pip-sEgGbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:21:48 INFO - Running setup.py (path:/tmp/pip-tx9gOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:21:48 INFO - Running setup.py (path:/tmp/pip-ILcBIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:21:48 INFO - Running setup.py (path:/tmp/pip-vcf6BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:48 INFO - Running setup.py (path:/tmp/pip-3b4Cao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:21:48 INFO - Running setup.py (path:/tmp/pip-8H2RPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:21:48 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)) 23:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:21:48 INFO - Running setup.py (path:/tmp/pip-XxP8S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:21:48 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)) 23:21:48 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)) 23:21:48 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)) 23:21:48 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)) 23:21:48 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)) 23:21:48 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)) 23:21:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:21:48 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 23:21:48 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 23:21:48 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 23:21:48 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 23:21:48 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 23:21:48 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 23:21:50 INFO - Downloading blessings-1.6.tar.gz 23:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:21:50 INFO - Installing collected packages: blessings 23:21:50 INFO - Running setup.py install for blessings 23:21:50 INFO - Successfully installed blessings 23:21:50 INFO - Cleaning up... 23:21:50 INFO - Return code: 0 23:21:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:21:50 INFO - Reading from file tmpfile_stdout 23:21:50 INFO - Current package versions: 23:21:50 INFO - argparse == 1.2.1 23:21:50 INFO - blessings == 1.6 23:21:50 INFO - blobuploader == 1.2.4 23:21:50 INFO - docopt == 0.6.1 23:21:50 INFO - functools32 == 3.2.3-2 23:21:50 INFO - jsonschema == 2.5.1 23:21:50 INFO - manifestparser == 1.1 23:21:50 INFO - mozInstall == 1.12 23:21:50 INFO - mozcrash == 0.17 23:21:50 INFO - mozdebug == 0.1 23:21:50 INFO - mozdevice == 0.48 23:21:50 INFO - mozfile == 1.2 23:21:50 INFO - mozhttpd == 0.7 23:21:50 INFO - mozinfo == 0.9 23:21:50 INFO - mozleak == 0.1 23:21:50 INFO - mozlog == 3.3 23:21:50 INFO - moznetwork == 0.27 23:21:50 INFO - mozprocess == 0.23 23:21:50 INFO - mozprofile == 0.28 23:21:50 INFO - mozrunner == 6.12 23:21:50 INFO - mozscreenshot == 0.1 23:21:50 INFO - mozsystemmonitor == 0.3 23:21:50 INFO - moztest == 0.7 23:21:50 INFO - mozversion == 1.4 23:21:50 INFO - psutil == 3.1.1 23:21:50 INFO - requests == 1.2.3 23:21:50 INFO - wsgiref == 0.1.2 23:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:21: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')]} 23:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:50 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 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:50 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 23:21:50 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 23:21:50 INFO - Using env: {'DISPLAY': ':0', 23:21:50 INFO - 'HOME': '/home/cltbld', 23:21:50 INFO - 'LANG': 'en_US.UTF-8', 23:21:50 INFO - 'LANGUAGE': 'en_US:en', 23:21:50 INFO - 'LOGNAME': 'cltbld', 23:21:50 INFO - 'MAIL': '/var/mail/cltbld', 23:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:50 INFO - 'MOZ_NO_REMOTE': '1', 23:21:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:50 INFO - 'NO_EM_RESTART': '1', 23:21:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:50 INFO - 'PWD': '/builds/slave/test', 23:21:50 INFO - 'SHELL': '/bin/bash', 23:21:50 INFO - 'SHLVL': '1', 23:21:50 INFO - 'TERM': 'linux', 23:21:50 INFO - 'TMOUT': '86400', 23:21:50 INFO - 'USER': 'cltbld', 23:21:50 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:50 INFO - '_': '/tools/buildbot/bin/python'} 23:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 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)) 23:21:51 INFO - Cleaning up... 23:21:51 INFO - Return code: 0 23:21:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcf3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfb4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', '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 23:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:21:51 INFO - Using env: {'DISPLAY': ':0', 23:21:51 INFO - 'HOME': '/home/cltbld', 23:21:51 INFO - 'LANG': 'en_US.UTF-8', 23:21:51 INFO - 'LANGUAGE': 'en_US:en', 23:21:51 INFO - 'LOGNAME': 'cltbld', 23:21:51 INFO - 'MAIL': '/var/mail/cltbld', 23:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:51 INFO - 'MOZ_NO_REMOTE': '1', 23:21:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:51 INFO - 'NO_EM_RESTART': '1', 23:21:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:21:51 INFO - 'PWD': '/builds/slave/test', 23:21:51 INFO - 'SHELL': '/bin/bash', 23:21:51 INFO - 'SHLVL': '1', 23:21:51 INFO - 'TERM': 'linux', 23:21:51 INFO - 'TMOUT': '86400', 23:21:51 INFO - 'USER': 'cltbld', 23:21:51 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:51 INFO - '_': '/tools/buildbot/bin/python'} 23:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:21:51 INFO - Cleaning up... 23:21:51 INFO - Return code: 0 23:21:51 INFO - Running post-action listener: _resource_record_post_action 23:21:51 INFO - Running post-action listener: _start_resource_monitoring 23:21:51 INFO - Starting resource monitoring. 23:21:51 INFO - [mozharness: 2016-08-25 06:21:51.276146Z] Finished create-virtualenv step (success) 23:21:51 INFO - [mozharness: 2016-08-25 06:21:51.276579Z] Running install step. 23:21:51 INFO - Running pre-action listener: _resource_record_pre_action 23:21:51 INFO - Running main action method: install 23:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:21:51 INFO - Reading from file tmpfile_stdout 23:21:51 INFO - Detecting whether we're running mozinstall >=1.0... 23:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:21:51 INFO - Reading from file tmpfile_stdout 23:21:51 INFO - Output received: 23:21:51 INFO - Usage: mozinstall [options] installer 23:21:51 INFO - Options: 23:21:51 INFO - -h, --help show this help message and exit 23:21:51 INFO - -d DEST, --destination=DEST 23:21:51 INFO - Directory to install application into. [default: 23:21:51 INFO - "/builds/slave/test"] 23:21:51 INFO - --app=APP Application being installed. [default: firefox] 23:21:51 INFO - mkdir: /builds/slave/test/build/application 23:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:22:08 INFO - Reading from file tmpfile_stdout 23:22:08 INFO - Output received: 23:22:08 INFO - /builds/slave/test/build/application/firefox/firefox 23:22:08 INFO - Running post-action listener: _resource_record_post_action 23:22:08 INFO - [mozharness: 2016-08-25 06:22:08.361725Z] Finished install step (success) 23:22:08 INFO - [mozharness: 2016-08-25 06:22:08.362003Z] Running run-tests step. 23:22:08 INFO - Running pre-action listener: _resource_record_pre_action 23:22:08 INFO - Running main action method: run_tests 23:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:22:08 INFO - Python 2.7.3 23:22:08 INFO - Return code: 0 23:22:08 INFO - grabbing minidump binary from tooltool 23:22:08 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')]} 23:22:08 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 0xfb23b0>, '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 0xfb32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:22:08 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 23:22:08 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 23:22:08 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 23:22:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:22:08 INFO - Return code: 0 23:22:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:22:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:22:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:22:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:22:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 23:22:08 INFO - Using env: {'DISPLAY': ':0', 23:22:08 INFO - 'HOME': '/home/cltbld', 23:22:08 INFO - 'LANG': 'en_US.UTF-8', 23:22:08 INFO - 'LANGUAGE': 'en_US:en', 23:22:08 INFO - 'LOGNAME': 'cltbld', 23:22:08 INFO - 'MAIL': '/var/mail/cltbld', 23:22:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:22:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:22:08 INFO - 'MOZ_NO_REMOTE': '1', 23:22:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:22:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:22:08 INFO - 'NO_EM_RESTART': '1', 23:22:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:22:08 INFO - 'PWD': '/builds/slave/test', 23:22:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:22:08 INFO - 'SHELL': '/bin/bash', 23:22:08 INFO - 'SHLVL': '1', 23:22:08 INFO - 'TERM': 'linux', 23:22:08 INFO - 'TMOUT': '86400', 23:22:08 INFO - 'USER': 'cltbld', 23:22:08 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153', 23:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:22:08 INFO - '_': '/tools/buildbot/bin/python'} 23:22:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 23:22:08 INFO - mozversion application_buildid: 20160824202526 23:22:08 INFO - mozversion application_changeset: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 23:22:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:22:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:22:08 INFO - mozversion application_name: Firefox 23:22:08 INFO - mozversion application_remotingname: firefox-dev 23:22:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:22:08 INFO - mozversion application_vendor: Mozilla 23:22:08 INFO - mozversion application_version: 50.0a2 23:22:08 INFO - mozversion platform_buildid: 20160824202526 23:22:08 INFO - mozversion platform_changeset: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 23:22:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:22:08 INFO - mozversion platform_version: 50.0a2 23:22:08 INFO - using testdate: 1472106128 23:22:08 INFO - actual date: 1472106128 23:22:08 INFO - starting webserver on 'localhost:51648' 23:22:08 INFO - SUITE-START | Running 2 tests 23:22:08 INFO - TEST-START | dromaeo_css 23:22:08 INFO - operating with platform_type : linux_ 23:22:08 INFO - Initialising browser for dromaeo_css test... 23:22:08 INFO - TEST-INFO | started process 9606 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2bEvwO/profile http://localhost:51648/getInfo.html) 23:22:12 INFO - PROCESS | 9606 | __metrics Screen width/height:1600/1200 23:22:12 INFO - PROCESS | 9606 | colorDepth:24 23:22:12 INFO - PROCESS | 9606 | Browser inner width/height: 1024/697 23:22:12 INFO - PROCESS | 9606 | __metrics 23:22:13 INFO - PROCESS | 9606 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:22:13 INFO - PROCESS | 9606 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:22:14 INFO - TEST-INFO | 9606: exit 0 23:22:14 INFO - Browser initialized. 23:22:14 INFO - Running cycle 1/1 for dromaeo_css test... 23:22:14 INFO - TEST-INFO | started process 9696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2bEvwO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:22:26 INFO - PROCESS | 9696 | RSS: Main: 144965632 23:22:26 INFO - PROCESS | 9696 | 23:23:17 INFO - PROCESS | 9696 | 1472106197465 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] 23:25:26 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51648/tests/dromaeo/cssquery-ext.html) 23:25:27 INFO - PROCESS | 9696 | RSS: Main: 276844544 23:25:27 INFO - PROCESS | 9696 | 23:28:30 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-ext.html (next: http://localhost:51648/tests/dromaeo/cssquery-jquery.html) 23:28:30 INFO - PROCESS | 9696 | RSS: Main: 237293568 23:28:30 INFO - PROCESS | 9696 | 23:31:32 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51648/tests/dromaeo/cssquery-mootools.html) 23:31:33 INFO - PROCESS | 9696 | RSS: Main: 175095808 23:31:33 INFO - PROCESS | 9696 | 23:34:35 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51648/tests/dromaeo/cssquery-prototype.html) 23:34:35 INFO - PROCESS | 9696 | RSS: Main: 187629568 23:34:35 INFO - PROCESS | 9696 | 23:37:38 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51648/tests/dromaeo/cssquery-yui.html) 23:37:38 INFO - PROCESS | 9696 | RSS: Main: 178397184 23:37:38 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | Cycle 1(1): loaded http://localhost:51648/tests/dromaeo/cssquery-yui.html (next: http://localhost:51648/tests/dromaeo/cssquery-dojo.html) 23:40:41 INFO - PROCESS | 9696 | RSS: Main: 260411392 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | __start_tp_report 23:40:41 INFO - PROCESS | 9696 | _x_x_mozilla_page_load 23:40:41 INFO - PROCESS | 9696 | _x_x_mozilla_page_load_details 23:40:41 INFO - PROCESS | 9696 | |i|pagename|runs| 23:40:41 INFO - PROCESS | 9696 | |0;dojo.html;3270.8855190068343;3271.1985563536923;3290.0458866928593;3320.6347301796814;3334.3998080345546;5703.287089113861;5795.71021448928;5795.797147099853;5810.477057064863;5817.476427121558;5759.769609215637;5798.246227990366;5813.622114562561;5814.680192589406;5824.592278540504;5139.511746384106;5200.245964335169;5239.973800130994;5298.947010529884;5334.946650533484;5282.471752824706;5329.173978033411;5334.066538355792;5338;5342.626016178869;5159.458256883029;5198.870028249286;5212;5212.5308722214995;5251.028559716446;5286.5506431952945;5291.391489978663;5319.228711836762;5322.627416080876;5353.544948679367;5040.949590504121;5114.309568208281;5139.922901156486;5216.765245563959;5222.895542089137;5674.404187560288;5715.571332150063;5716.228309178249;5736.110902810071;5737.3688894221605;6189.9381006189815;6201.689915504252;6214.067889816519;6219.191505104308;6220.813375598739;5986.760529578812;6110.450059494622;6129.049997250433;6130.184124957599;6242.282137554194;5781.161731548902;5787.045137552299;5791.449812267828;5812.534997200214;5816.651000939957;6267.561270711098;6318.968405157945;6338.112664227012;6344.809655710336;6348.333424990309;5911.467967882912;6004;6033.5474839387225;6038.456538911519;6108.6029408089225;6190.3190649028575;6210.72051757672;6215.595986260879;6216.7824126155365;6219.751209951552;6149.108379284979;6162.26052873658;6162.661053642093;6163.630182189083;6164.599301045418;5969.641821490724;6013.6993150342305;6048.274207095177;6099.3900609938655;6112.083187521818;4651.930221046687;4702.600278976257;4709.552592503706;4726.385569875963;4741.762911854463;4177.352510360899;4194.182134483807;4199.223143718422;4202.03353228753;4227.492700875914;5729.5702822288495;5840.532757379399;5846.473817356458;5849.356570777211;5914.083317085859;6227.06594010893;6240.282367527701;6245.156903818017;6246.656433896089;6250.499960003189;5925.170476133345;5941.405859414024;5941.435563621449;5941.6493181795095;5977.820665380045;5192.870178245498;5273.947260527422;5322.600804939645;5361.651492652965;5367.275417818544;6397.776077837253;6433.613983161025;6434.549581529342;6444.226692796894;6454.838629034218;6203.689815509297;6267.341929097379;6268.7492500301305;6270.0908368287255;6272.937270627235;6188.381161883775;6245.7189426476825;6332.588381755171;6395.488360931207;6465.612063276219;6084.482818960438;6085.322139185672;6102.511799056154;6106.297775755845;6134.4172303631085;6161.4146656067605;6187.690615469119;6193.287771906288;6195.752169913333;6199.814005579839;5839.649621022752;5998.0103282957825;6033.909491357545;6072.1781571088995;6107.053406721965;6023.277206735044;6060.151578778887;6065.636061836304;6080.908786368296;6107.816765497042;5962.612430192024;6010.459058684739;6019.398060193946;6024.186734790747;6033.87932241344;5995.2206213192;6012.969935150471;6034.939650603614;6096.146539484387;6099.993501072273;5926.407359264039;5926.762929482945;5932.525397968066;5933.2880054393745;5950.583459157818 23:40:41 INFO - PROCESS | 9696 | |1;ext.html;600.0968542343772;643.8261669349275;668.8695704337653;676.435176627516;680.4182424027454;34698.479522807145;37872.24255514888;38114.23771524575;38182.42726359103;47196.76401617987;43795;47826.282605760854;47891.28163077548;48004.51995480044;48042.27936580954;8991.865122023159;9193.770155746126;9194.448333100003;9194.540272986358;9195.770105747342;8338.207870252316;8548.230659240668;9063.13900179482;9153.816923661523;9157.587908544097;10875.293105948089;11189.49647265875;11198.160137989642;11533.884661153406;11541;10905.890941090567;11002.669919902415;11062.668119956414;11067.335959842396;11067.72330691731;10778.191635627281;10868.782624347468;10872.67381978534;10873.238873278873;10875.728106797314;44423;44747.10505789866;44801;44893.32660010103;44898.77550612258;3850.268448994682;4273.215014482955;4480.14877173336;4594.931076033862;4715.504871988461;34350.65649343524;34510.82744586286;35680.464793027866;35771.4634280486;36387;4555.339475776027;4596.195665758512;4599.724016559017;4681.9297710534365;4829.396325459317;390722;390999;391553.0422347899;392248;392377;64669.67665161738;65137;65144.348556513825;65183.34816651868;65273.67363163154;39836;40712;40833.59166408298;42217;66928.33071669222;19823.30618428377;20039;20053.49866253326;20061.899690501745;20077;15051.698966020836;15122.848771512365;15130.394784208509;15132.924335378473;15136.772948405784;19032.61934761297;20198.192072317237;20684.896575517025;20964.79035209659;21044.47388815292;7401.59291238987;7553.622318884034;7642.159362470124;7773.3004029637605;7877.369810415153;1036.7356324137297;1108.5898217659517;1117.1342209787479;1142.5772464188303;1239.0521251242806;33188.668113318556;35403.468947965805;35684.82157589247;36818.07954801151;38289;1194.6057800925673;1202.801537746262;1221.322166197751;1222.1689251309194;1241.3855141704914;18085.457436276934;18130.728039079426;18243.310454590617;18308.633827323378;18406.447806565826;668933;678798;679797;680007;680039;62800;63124;63271.36728632747;63288;63392.68303658452;1407;1419.84381718013;1456.3810380588206;1506.7363211438173;1543.6063803729976;34227.65772342344;35778.64221357753;36061.81969090138;36121;36345.454818178274;53469.19796202982;54124.729376352865;54161;54209;54276;491368;495290;496558;496877;497738;29172.708272917847;30149.246268842577;30168.09495715132;30173.849130754206;30204.697953021066;49704.75147624239;50587.49412505975;51083.74458127686;51116.74441627768;51136.74431627818;42734;43278.783606081764;43320.56679433165;44052;44406;41951.7902410486;42211.577884221995;42470.362944555236;42917.1416571673;43595;42890.35664465098;43347.34978975381;44753;44812.10375792401;44869.10261794811;35953;38015.23969520539;38837.6116238834;38960.610393895695;39136.412953806284;29465;29622.407551849272;29647.407051858412;29928;29984.25039374033 23:40:41 INFO - PROCESS | 9696 | |2;jquery.html;388.2719900187149;416.2278972506001;420.9074003719182;423.7817523975153;445.427625501231;15183.620409489768;20307.898460507695;20339.389818305437;20509.384718458452;20824;19847.40457786265;19861.602767944667;20125.39623811288;20174.596508069866;20215.191392344324;5710.7715691372505;5794.797182098628;5867.354590994987;5907.409259074079;5913.704314784265;5872.060470324749;5873.765049398019;5901.557383196277;5906.586538942276;5907;5713.514351280146;5891.793787217448;5919.408059194067;5924.200232968558;5929.495992840592;5714.028615135396;5746.138079288098;5859.179714839905;5896.174535565025;5908.497777688902;5184.662996905189;5242.580593552545;5242.68543887368;5246.580273578143;5247.291615631879;23267.3019809406;23457.53084938309;23573.29280121599;23597.7640223599;23612.17357392483;2434.267838758208;2619.1356852238905;2693.6498255226893;2767;3063.9234019149476;14746.336414861356;14900.776488352683;14960.925195374062;15513.991590546579;15663.530094097081;2016.2136766661015;2017.2233690029298;2025.0887100804696;2036.6232247034345;2095.8323334133383;82321.78963004147;82435;82929;83077;84404.15595844085;10898.94550527258;10974.725631859268;11207.327560346404;11341.603043893425;11529.769404611861;15775.921120394325;15797.684046319238;15813.841861581466;15831.920840395724;15857.682846343008;8590.355723320776;8889.688860889837;8899.822003559893;8958.462492250486;9012.774680633036;8767.342449316327;8775.561221938877;8844.557772111497;9011.414258073204;9029.097090290918;6792.898106528406;6804.863902722017;6892.517523773287;6969.4424446044195;6983.511154219156;2034.6846238833004;2078.3349328215127;2080.5318803269142;2082.5938941906184;2116.7248257726396;962.764122789921;1059.06802014227;1066.626680661762;1073.8067147913293;1093.7858976536038;20331.695024574645;20727.481812954797;20737;20747.68878466824;21020.264290750056;1087.6138970665459;1090.432974853072;1107.9556817727362;1122.141561705296;1128.1820680006929;10947.726306842393;11746.689935315268;11914;11952.880471195176;11996.580119695944;498637;515923;531319;532601;535037;83025.58487207525;86219.56890215634;87617;87693;88439.90460333986;1090.9399983000853;1102.9191392435298;1107.2858006585707;1109.7392112853472;1120.6301920366259;16551;18263.933248163823;18450.538736531693;18586.721199181753;18587.535311617317;41841.79079104707;42023;42735.7863210682;42764;43680;56649.150262745265;57148;57460.42539574718;57934.7103264481;59116.704416477645;8442.915570844458;8653.653853845775;8663.653453861774;8815.559222038744;9034.051424600322;12354;13162;13807.516736914562;13862.306884655525;14223.715525689635;11979.401029948642;12307.56923507673;12348.3825808711;12436.316002619942;12560.37198140071;11547.884521155018;11558;11723.41382930867;12013.939930300292;12373.443195056065;11799.82300265514;12192.634220973385;12317.876821231674;12362.258264504158;12416;9206.49364284944;9262;9341.906580934103;9348.766280843034;9416.623335066795;11418.82871756941;11554;11625;11748;11943.940280298542 23:40:41 INFO - PROCESS | 9696 | |3;mootools.html;1396.4274647394557;1536.9077855328678;1617.2479796894443;1789.2127463915876;1939.0692467615536;9344.953275233624;9781.070798274162;9803.117719405253;10086.193104551636;10090.747731306721;9847.21222302216;9960.402375857453;9960.551775170117;9964.900350996488;9970.202383809295;2525.83582067166;2535.9746402536016;2548.872556372174;2555.9361015974655;2559.5392829290813;2453.0678342229926;2481.1192026830486;2493.0277191895175;2493.7007559092845;2495.663085483455;2454.9386265343333;2457.6559281700575;2519.143491212987;2521.10500772226;2526.12848567244;2433.0754313360985;2433.987830060847;2446.412860913377;2487.8009759219217;2496.6255061740885;2415.40822498489;2424.3090719145134;2429.817763667732;2436.427439551704;2486.465409936854;9777.90222097777;9919.851202231972;9956.303058785888;9970.800583988279;9978.700638980843;5684.346300175492;5902.822915312548;5907.5864689471755;5914.822555323347;5917.082852157922;3590.7666001710068;3737.663610275088;3738;3792.9620703792884;3807.7144214184045;1243.9129260951736;1262.6111277594664;1267.3283159925254;1308.5223893278885;1328.6479083042905;14234.92882535591;14376.712465750627;14377.71244575113;14385.856141438557;14390.568282951528;4831.661783675109;4860.222364421676;4967.155583550805;5028.5977121830165;5080.974595127074;11035.668929932115;11051.447427628586;11053.336799792038;11065.668029959113;11067.225294229487;5858.326292476335;5859.44335288147;5861.120831875252;5862.325832529228;5866.6480011199465;4596.37948876904;5203.8178663746585;5241.659292145999;5315.04329220744;5440.265564148789;4104.727731556317;4155.667546596265;4204.831806727697;4239.97880010598;4246.341817018367;1222.3032871263295;1223.1193540650718;1226.45422786859;1227.594404406967;1247.1207798502035;915.8809354783969;975.8975307592742;1022.8056669232822;1029.351508549609;1048.7483004078965;6275.497960163176;6342.587731797418;6402.903956440658;6454.386833250834;6725.05849181115;491.7516654089662;492.31076492909494;496.659788045183;499.9750012499433;502.1037448155026;4535.387722657399;4642.396488456479;4671.8787491002595;4765.73788441639;4790.568848803624;12183.147179697513;12563.057770667236;12576.748465030649;12581.559645412366;12669.809952850715;11245.212835101627;11655.708607284885;11670.941645291889;12110.515579376726;12503.499860005499;9106.86339704905;9290.16388525036;9321.72034838956;9333.813323733488;9345.15893569582;6936.965315173392;7217.711291548279;7233.421326293989;7257.600831954293;7278.854422911406;11481.770364592829;11533.423328833356;11693.473793679475;11699.12256580777;11790.41047947582;11555.4800033997;11641.47613357384;11674.124440666814;11976.820347694616;11990.160688752052;7676.6161691916295;8547.487150770974;8584.270337021424;8712.91287087121;8781.473111613324;9604.711858644252;9659.758506037124;9663.613455461702;9685.370450920667;9691.466969316472;8421.410501264852;8464.957675211585;8474.533900635524;8482.745517634481;8484.957575212084;8332.375071869512;8346.415750897379;8423.873641895254;8433.746987590383;8447.239748422671;7579.0905091387185;7649.196834332315;7649.388048956182;7654;7729.111152217342;5925.792597259076;5981;6004.42957918997;6025.18659980897;6227.844303892439;5827.912581311369;5832;5844.766209351578;5911.615744976562;6051.909221361771 23:40:41 INFO - PROCESS | 9696 | |4;prototype.html;339.62301844952106;351.42366518908995;352.1794219468637;367.8804388573714;367.9172186258091;5296.232046353276;5447.645903016311;5470.917936230955;5533.391326954037;5542.75057622407;5438.401775804658;5461.15352120421;5467.441779092949;5471.768852008297;5481.287432633762;4359.542248063955;4362.149380870724;4364.803583838719;4367.956320436787;4370.38814565961;4149.609880689973;4328.502222244435;4364.629006534448;4369.344598310263;4372.497162826268;4294.67789915756;4323.286657701476;4336.501302350222;4347.760873151976;4352.107817897339;4320.200762858881;4335.826566937319;4337.175936572041;4339.609435150852;4360.454943132077;3626.782393056425;3882.3594106972596;3884.6503814656817;3886.3393223152134;3892.1826416452577;5337.919931201035;5343.812966546191;5356.901835123829;5366.517013468807;5377.677339359651;4951.702897826142;4962.106820772259;4965.280034395028;4969.428515720702;4974.701517908937;5472.398036216013;5694.430556944314;5744.3394009689;5747.913781293284;5762.30852297727;4153.979230103831;4168.520620128664;4168.958310416887;4183.6234738873345;4199.7531320743565;5905.793297234576;5953.136795164677;5957.344692083867;5965.940340596582;5972.671503067286;4178.7283826551175;4244.257254980366;4257.914841703148;4277.914441711148;4278.871633850989;4307.1601037797955;4309.547497512779;4331.393604895317;4334.891627709332;4335.978320108379;4910.017996400663;4917.065757506062;4917.385326834145;4918.311436398907;4925.285833554115;5439.347278326626;5712.80005199816;5762.674584845509;5777.913331300034;5781.653100813881;1450.724362371146;1464.4947493114857;1473.710503309604;1478;1481.9333130009175;906.7914379692708;946.8674385586023;958.6357184269933;973.9415635061778;975.9170470509944;5020.472850350734;5021.020900924284;5032.220005899091;5036.370453693288;5040;5219.982103489783;5237.292965449692;5260.684358938476;5262.368665713641;5266.4470230626;4979.775910084055;4984.476629953875;5010.223415370623;5299.701573114611;5688.288963879515;3133.9843300783805;3157.41587806252;3162.3517178978254;3166.82582457967;3169.984150079235;6062.757489700451;6077.848053798691;6087.421694938974;6093.482054025458;6110.419510146529;5796.942030579725;6042.425969532887;6061.030235162353;6085.6044357117535;6090.299615544181;6142;6148.907766383508;6153.2000839890525;6154.907676384858;6161.322254551996;5697.401772813795;5870.7945221918735;5902.439268269508;5954.642721436728;5974.641521508724;5829.8251052468495;5869.911951320648;5872.911906321323;5882.352941176381;5884.558658100574;5845.503132233808;6161.537884658579;6162.907556386568;6176.907346389898;6190.226221722285;4200.621944024931;4255.723377980421;4259.510156332061;4261.701680882308;4271.163399738142;5933.139694744152;5940.316863560746;5946.583739138392;5976.402359763989;5978.372270911492;5674.687892165805;5791.333996590446;5840.94159058404;5855.23881895368;5862.530997520274;5809.651420914759;5809.883802324014;5833.154192642128;5837.620554663933;5842.1820945066875;5765.509931655856;5770.826875193751;5770.8845823082465;5781.762213160643;5794.62334948227;5622.24099746529;5808.854778630568;5815.185873977731;5822;5827.854303642443;5687.976164290302;5690.430956904442;5691.975444420044;5694.686792226467;5711.914321285267 23:40:41 INFO - PROCESS | 9696 | |5;yui.html;761.3452430909418;779.1507257089771;801.4870482890951;831.3681601982491;876.9914598212051;17113.743293850588;17372.826271737296;17692.911535442337;17856;17885.910570447162;16332.918335408307;16474.176291185453;16492.17539123042;16498.25757840897;16510.83489165108;758.2341834746918;880.1286726141107;904.6833608237097;910.2354022620997;926.5691453474133;889.1020069729587;898.1512470715177;898.5417437107058;899.3929097858952;899.9730008099767;932.8460803967337;935.7988032572996;937.386012162035;937.5734041011358;937.7843096087904;783.2872086401347;921.6083164654995;928.0487500312166;930.2743859789375;937.4890684576922;684.910961574992;692.7748481743454;696.8501772119018;696.8571442854253;697.1982220446527;15264.084154950739;15315.770263445946;15428.768568471482;15520.223988800628;15559.37762489499;15114.697706045927;15505.689886202326;15669.843301567067;15837.683246335124;15975.520734377988;13963.441462341494;13965.58103256904;14081.366338514792;14083.225422601752;14127.223002734845;4630.282306242538;4644.027822193799;4650.511696271877;4729.9763501182615;4738.549837765441;81609;84503;85111.57444212801;85736.57131714364;86544.13455865423;12248.938755306166;12366.44351004207;12438.19151755133;12589.622311330675;12604.936975315248;22652.207172749204;22778.20276290322;22846;23001.654975175385;23583;8918.37571369998;8947.150020748019;9031.548422578844;9032.412893161923;9539.09378609031;5100.438951715309;5188.11801993662;5274.841754747364;5304.204369344552;5307.575393968474;3848.5189351331082;3988.4216788565495;4006.979965100156;4016.357382818735;4024.959750402517;686.842026333946;693.7962634828568;698.3819319901924;704.9612271325126;747.8018325143842;720.020771750419;723.5152447859947;768.7155752371658;812.1878121878121;825.0511911302051;10291.897081029243;10859.294145880493;10867.34795912232;10983.725406864733;11032.393218373067;683.5420268420171;685.338648204485;767.7005967672612;790.3992965346379;805.7260531419346;5092.9236061459105;5384.111621582473;5387.24578559002;5416.674999500043;5422.75597598109;74799.62600186965;81651.18348816436;134463;134521;134741;113936.43031784787;114874;115166;118433;120437.39781301038;86330;100049;101591;102670;105039.47480262702;10047;10228.386296822215;10269.383836969806;10582.682519524427;10648.8935110651;56840.71579642075;57080.714596426755;58481.122783157436;59859.40140598538;59953.700231498566;79679.60160199161;87297.56351218204;88348.55825720831;90359.54820225856;93818.53090734503;12948.15836970594;13304.46782128704;13308.268045257604;13349.332533373099;13407.463701452223;14916.925415372854;15110.320035598645;15256.160911149993;15393.599976041143;16126.435574754827;13018.80471792905;13405.128666636638;13459.932700336436;13515.459381624625;13673.658158545719;13280.203187808762;13487.123336983064;13500;13606.659833504242;14243.928780356446;14378.28108594587;14422.495212667507;14425.711485770436;15321.157336346609;15563.910526263053;10646.361218326925;11144.888551114385;11287.604933827277;11555.826662600235;11685;11342.546298147981;11881.762364752829;12820.294883781482;13465.326733663474;13833.723325533634 23:40:41 INFO - PROCESS | 9696 | __end_tp_report 23:40:41 INFO - PROCESS | 9696 | __start_cc_report 23:40:41 INFO - PROCESS | 9696 | _x_x_mozilla_cycle_collect,1140 23:40:41 INFO - PROCESS | 9696 | __end_cc_report 23:40:41 INFO - PROCESS | 9696 | __startTimestamp1472107241561__endTimestamp 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | ------- Summary: start ------- 23:40:41 INFO - PROCESS | 9696 | Number of tests: 6 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#0] dojo.html Cycles:165 Average:5737.40 Median:5969.64 stddev:660.09 (11.1%) stddev-sans-first:633.12 23:40:41 INFO - PROCESS | 9696 | Values: 3270.9 3271.2 3290.0 3320.6 3334.4 5703.3 5795.7 5795.8 5810.5 5817.5 5759.8 5798.2 5813.6 5814.7 5824.6 5139.5 5200.2 5240.0 5298.9 5334.9 5282.5 5329.2 5334.1 5338.0 5342.6 5159.5 5198.9 5212.0 5212.5 5251.0 5286.6 5291.4 5319.2 5322.6 5353.5 5040.9 5114.3 5139.9 5216.8 5222.9 5674.4 5715.6 5716.2 5736.1 5737.4 6189.9 6201.7 6214.1 6219.2 6220.8 5986.8 6110.5 6129.0 6130.2 6242.3 5781.2 5787.0 5791.4 5812.5 5816.7 6267.6 6319.0 6338.1 6344.8 6348.3 5911.5 6004.0 6033.5 6038.5 6108.6 6190.3 6210.7 6215.6 6216.8 6219.8 6149.1 6162.3 6162.7 6163.6 6164.6 5969.6 6013.7 6048.3 6099.4 6112.1 4651.9 4702.6 4709.6 4726.4 4741.8 4177.4 4194.2 4199.2 4202.0 4227.5 5729.6 5840.5 5846.5 5849.4 5914.1 6227.1 6240.3 6245.2 6246.7 6250.5 5925.2 5941.4 5941.4 5941.6 5977.8 5192.9 5273.9 5322.6 5361.7 5367.3 6397.8 6433.6 6434.5 6444.2 6454.8 6203.7 6267.3 6268.7 6270.1 6272.9 6188.4 6245.7 6332.6 6395.5 6465.6 6084.5 6085.3 6102.5 6106.3 6134.4 6161.4 6187.7 6193.3 6195.8 6199.8 5839.6 5998.0 6033.9 6072.2 6107.1 6023.3 6060.2 6065.6 6080.9 6107.8 5962.6 6010.5 6019.4 6024.2 6033.9 5995.2 6013.0 6034.9 6096.1 6100.0 5926.4 5926.8 5932.5 5933.3 5950.6 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#1] ext.html Cycles:180 Average:68223.58 Median:40274.00 stddev:142491.65 (353.8%) stddev-sans-first:142800.92 23:40:41 INFO - PROCESS | 9696 | Values: 600.1 643.8 668.9 676.4 680.4 34698.5 37872.2 38114.2 38182.4 47196.8 43795.0 47826.3 47891.3 48004.5 48042.3 8991.9 9193.8 9194.4 9194.5 9195.8 8338.2 8548.2 9063.1 9153.8 9157.6 10875.3 11189.5 11198.2 11533.9 11541.0 10905.9 11002.7 11062.7 11067.3 11067.7 10778.2 10868.8 10872.7 10873.2 10875.7 44423.0 44747.1 44801.0 44893.3 44898.8 3850.3 4273.2 4480.1 4594.9 4715.5 34350.7 34510.8 35680.5 35771.5 36387.0 4555.3 4596.2 4599.7 4681.9 4829.4 390722.0 390999.0 391553.0 392248.0 392377.0 64669.7 65137.0 65144.3 65183.3 65273.7 39836.0 40712.0 40833.6 42217.0 66928.3 19823.3 20039.0 20053.5 20061.9 20077.0 15051.7 15122.8 15130.4 15132.9 15136.8 19032.6 20198.2 20684.9 20964.8 21044.5 7401.6 7553.6 7642.2 7773.3 7877.4 1036.7 1108.6 1117.1 1142.6 1239.1 33188.7 35403.5 35684.8 36818.1 38289.0 1194.6 1202.8 1221.3 1222.2 1241.4 18085.5 18130.7 18243.3 18308.6 18406.4 668933.0 678798.0 679797.0 680007.0 680039.0 62800.0 63124.0 63271.4 63288.0 63392.7 1407.0 1419.8 1456.4 1506.7 1543.6 34227.7 35778.6 36061.8 36121.0 36345.5 53469.2 54124.7 54161.0 54209.0 54276.0 491368.0 495290.0 496558.0 496877.0 497738.0 29172.7 30149.2 30168.1 30173.8 30204.7 49704.8 50587.5 51083.7 51116.7 51136.7 42734.0 43278.8 43320.6 44052.0 44406.0 41951.8 42211.6 42470.4 42917.1 43595.0 42890.4 43347.3 44753.0 44812.1 44869.1 35953.0 38015.2 38837.6 38960.6 39136.4 29465.0 29622.4 29647.4 29928.0 29984.3 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#2] jquery.html Cycles:180 Average:30323.62 Median:23515.41 stddev:85872.71 (365.2%) stddev-sans-first:86084.18 23:40:41 INFO - PROCESS | 9696 | Values: 388.3 416.2 420.9 423.8 445.4 15183.6 20307.9 20339.4 20509.4 20824.0 19847.4 19861.6 20125.4 20174.6 20215.2 5710.8 5794.8 5867.4 5907.4 5913.7 5872.1 5873.8 5901.6 5906.6 5907.0 5713.5 5891.8 5919.4 5924.2 5929.5 5714.0 5746.1 5859.2 5896.2 5908.5 5184.7 5242.6 5242.7 5246.6 5247.3 23267.3 23457.5 23573.3 23597.8 23612.2 2434.3 2619.1 2693.6 2767.0 3063.9 14746.3 14900.8 14960.9 15514.0 15663.5 2016.2 2017.2 2025.1 2036.6 2095.8 82321.8 82435.0 82929.0 83077.0 84404.2 10898.9 10974.7 11207.3 11341.6 11529.8 15775.9 15797.7 15813.8 15831.9 15857.7 8590.4 8889.7 8899.8 8958.5 9012.8 8767.3 8775.6 8844.6 9011.4 9029.1 6792.9 6804.9 6892.5 6969.4 6983.5 2034.7 2078.3 2080.5 2082.6 2116.7 962.8 1059.1 1066.6 1073.8 1093.8 20331.7 20727.5 20737.0 20747.7 21020.3 1087.6 1090.4 1108.0 1122.1 1128.2 10947.7 11746.7 11914.0 11952.9 11996.6 498637.0 515923.0 531319.0 532601.0 535037.0 83025.6 86219.6 87617.0 87693.0 88439.9 1090.9 1102.9 1107.3 1109.7 1120.6 16551.0 18263.9 18450.5 18586.7 18587.5 41841.8 42023.0 42735.8 42764.0 43680.0 56649.2 57148.0 57460.4 57934.7 59116.7 8442.9 8653.7 8663.7 8815.6 9034.1 12354.0 13162.0 13807.5 13862.3 14223.7 11979.4 12307.6 12348.4 12436.3 12560.4 11547.9 11558.0 11723.4 12013.9 12373.4 11799.8 12192.6 12317.9 12362.3 12416.0 9206.5 9262.0 9341.9 9348.8 9416.6 11418.8 11554.0 11625.0 11748.0 11943.9 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#3] mootools.html Cycles:180 Average:6467.96 Median:4811.12 stddev:3791.46 (78.8%) stddev-sans-first:3782.94 23:40:41 INFO - PROCESS | 9696 | Values: 1396.4 1536.9 1617.2 1789.2 1939.1 9345.0 9781.1 9803.1 10086.2 10090.7 9847.2 9960.4 9960.6 9964.9 9970.2 2525.8 2536.0 2548.9 2555.9 2559.5 2453.1 2481.1 2493.0 2493.7 2495.7 2454.9 2457.7 2519.1 2521.1 2526.1 2433.1 2434.0 2446.4 2487.8 2496.6 2415.4 2424.3 2429.8 2436.4 2486.5 9777.9 9919.9 9956.3 9970.8 9978.7 5684.3 5902.8 5907.6 5914.8 5917.1 3590.8 3737.7 3738.0 3793.0 3807.7 1243.9 1262.6 1267.3 1308.5 1328.6 14234.9 14376.7 14377.7 14385.9 14390.6 4831.7 4860.2 4967.2 5028.6 5081.0 11035.7 11051.4 11053.3 11065.7 11067.2 5858.3 5859.4 5861.1 5862.3 5866.6 4596.4 5203.8 5241.7 5315.0 5440.3 4104.7 4155.7 4204.8 4240.0 4246.3 1222.3 1223.1 1226.5 1227.6 1247.1 915.9 975.9 1022.8 1029.4 1048.7 6275.5 6342.6 6402.9 6454.4 6725.1 491.8 492.3 496.7 500.0 502.1 4535.4 4642.4 4671.9 4765.7 4790.6 12183.1 12563.1 12576.7 12581.6 12669.8 11245.2 11655.7 11670.9 12110.5 12503.5 9106.9 9290.2 9321.7 9333.8 9345.2 6937.0 7217.7 7233.4 7257.6 7278.9 11481.8 11533.4 11693.5 11699.1 11790.4 11555.5 11641.5 11674.1 11976.8 11990.2 7676.6 8547.5 8584.3 8712.9 8781.5 9604.7 9659.8 9663.6 9685.4 9691.5 8421.4 8465.0 8474.5 8482.7 8485.0 8332.4 8346.4 8423.9 8433.7 8447.2 7579.1 7649.2 7649.4 7654.0 7729.1 5925.8 5981.0 6004.4 6025.2 6227.8 5827.9 5832.0 5844.8 5911.6 6051.9 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#4] prototype.html Cycles:180 Average:4834.11 Median:5438.87 stddev:1409.55 (25.9%) stddev-sans-first:1372.55 23:40:41 INFO - PROCESS | 9696 | Values: 339.6 351.4 352.2 367.9 367.9 5296.2 5447.6 5470.9 5533.4 5542.8 5438.4 5461.2 5467.4 5471.8 5481.3 4359.5 4362.1 4364.8 4368.0 4370.4 4149.6 4328.5 4364.6 4369.3 4372.5 4294.7 4323.3 4336.5 4347.8 4352.1 4320.2 4335.8 4337.2 4339.6 4360.5 3626.8 3882.4 3884.7 3886.3 3892.2 5337.9 5343.8 5356.9 5366.5 5377.7 4951.7 4962.1 4965.3 4969.4 4974.7 5472.4 5694.4 5744.3 5747.9 5762.3 4154.0 4168.5 4169.0 4183.6 4199.8 5905.8 5953.1 5957.3 5965.9 5972.7 4178.7 4244.3 4257.9 4277.9 4278.9 4307.2 4309.5 4331.4 4334.9 4336.0 4910.0 4917.1 4917.4 4918.3 4925.3 5439.3 5712.8 5762.7 5777.9 5781.7 1450.7 1464.5 1473.7 1478.0 1481.9 906.8 946.9 958.6 973.9 975.9 5020.5 5021.0 5032.2 5036.4 5040.0 5220.0 5237.3 5260.7 5262.4 5266.4 4979.8 4984.5 5010.2 5299.7 5688.3 3134.0 3157.4 3162.4 3166.8 3170.0 6062.8 6077.8 6087.4 6093.5 6110.4 5796.9 6042.4 6061.0 6085.6 6090.3 6142.0 6148.9 6153.2 6154.9 6161.3 5697.4 5870.8 5902.4 5954.6 5974.6 5829.8 5869.9 5872.9 5882.4 5884.6 5845.5 6161.5 6162.9 6176.9 6190.2 4200.6 4255.7 4259.5 4261.7 4271.2 5933.1 5940.3 5946.6 5976.4 5978.4 5674.7 5791.3 5840.9 5855.2 5862.5 5809.7 5809.9 5833.2 5837.6 5842.2 5765.5 5770.8 5770.9 5781.8 5794.6 5622.2 5808.9 5815.2 5822.0 5827.9 5688.0 5690.4 5692.0 5694.7 5711.9 23:40:41 INFO - PROCESS | 9696 | 23:40:41 INFO - PROCESS | 9696 | [#5] yui.html Cycles:180 Average:22904.90 Median:23292.33 stddev:33374.28 (143.3%) stddev-sans-first:33426.49 23:40:41 INFO - PROCESS | 9696 | Values: 761.3 779.2 801.5 831.4 877.0 17113.7 17372.8 17692.9 17856.0 17885.9 16332.9 16474.2 16492.2 16498.3 16510.8 758.2 880.1 904.7 910.2 926.6 889.1 898.2 898.5 899.4 900.0 932.8 935.8 937.4 937.6 937.8 783.3 921.6 928.0 930.3 937.5 684.9 692.8 696.9 696.9 697.2 15264.1 15315.8 15428.8 15520.2 15559.4 15114.7 15505.7 15669.8 15837.7 15975.5 13963.4 13965.6 14081.4 14083.2 14127.2 4630.3 4644.0 4650.5 4730.0 4738.5 81609.0 84503.0 85111.6 85736.6 86544.1 12248.9 12366.4 12438.2 12589.6 12604.9 22652.2 22778.2 22846.0 23001.7 23583.0 8918.4 8947.2 9031.5 9032.4 9539.1 5100.4 5188.1 5274.8 5304.2 5307.6 3848.5 3988.4 4007.0 4016.4 4025.0 686.8 693.8 698.4 705.0 747.8 720.0 723.5 768.7 812.2 825.1 10291.9 10859.3 10867.3 10983.7 11032.4 683.5 685.3 767.7 790.4 805.7 5092.9 5384.1 5387.2 5416.7 5422.8 74799.6 81651.2 134463.0 134521.0 134741.0 113936.4 114874.0 115166.0 118433.0 120437.4 86330.0 100049.0 101591.0 102670.0 105039.5 10047.0 10228.4 10269.4 10582.7 10648.9 56840.7 57080.7 58481.1 59859.4 59953.7 79679.6 87297.6 88348.6 90359.5 93818.5 12948.2 13304.5 13308.3 13349.3 13407.5 14916.9 15110.3 15256.2 15393.6 16126.4 13018.8 13405.1 13459.9 13515.5 13673.7 13280.2 13487.1 13500.0 13606.7 14243.9 14378.3 14422.5 14425.7 15321.2 15563.9 10646.4 11144.9 11287.6 11555.8 11685.0 11342.5 11881.8 12820.3 13465.3 13833.7 23:40:41 INFO - PROCESS | 9696 | -------- Summary: end -------- 23:40:41 INFO - PROCESS | 9696 | 23:40:42 INFO - TEST-INFO | 9696: exit 0 23:40:42 INFO - TEST-OK | dromaeo_css | took 1113678ms 23:40:42 INFO - TEST-START | kraken 23:40:42 INFO - operating with platform_type : linux_ 23:40:42 INFO - Initialising browser for kraken test... 23:40:42 INFO - TEST-INFO | started process 9896 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWeS0i/profile http://localhost:51648/getInfo.html) 23:40:46 INFO - PROCESS | 9896 | __metrics Screen width/height:1600/1200 23:40:46 INFO - PROCESS | 9896 | colorDepth:24 23:40:46 INFO - PROCESS | 9896 | Browser inner width/height: 1024/697 23:40:46 INFO - PROCESS | 9896 | __metrics 23:40:47 INFO - PROCESS | 9896 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:40:47 INFO - PROCESS | 9896 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:40:48 INFO - TEST-INFO | 9896: exit 0 23:40:48 INFO - Browser initialized. 23:40:48 INFO - Running cycle 1/1 for kraken test... 23:40:48 INFO - TEST-INFO | started process 9981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBWeS0i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:41:00 INFO - PROCESS | 9981 | RSS: Main: 145313792 23:41:00 INFO - PROCESS | 9981 | 23:41:02 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51648/tests/kraken/driver.html?testName=audio-beat-detection) 23:41:03 INFO - PROCESS | 9981 | RSS: Main: 191873024 23:41:03 INFO - PROCESS | 9981 | 23:41:06 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51648/tests/kraken/driver.html?testName=audio-dft) 23:41:06 INFO - PROCESS | 9981 | RSS: Main: 181927936 23:41:06 INFO - PROCESS | 9981 | 23:41:09 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51648/tests/kraken/driver.html?testName=audio-fft) 23:41:10 INFO - PROCESS | 9981 | RSS: Main: 269201408 23:41:10 INFO - PROCESS | 9981 | 23:41:12 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51648/tests/kraken/driver.html?testName=audio-oscillator) 23:41:12 INFO - PROCESS | 9981 | RSS: Main: 175607808 23:41:12 INFO - PROCESS | 9981 | 23:41:14 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51648/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:41:15 INFO - PROCESS | 9981 | RSS: Main: 177496064 23:41:15 INFO - PROCESS | 9981 | 23:41:18 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51648/tests/kraken/driver.html?testName=imaging-darkroom) 23:41:19 INFO - PROCESS | 9981 | RSS: Main: 213176320 23:41:19 INFO - PROCESS | 9981 | 23:41:22 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51648/tests/kraken/driver.html?testName=imaging-desaturate) 23:41:23 INFO - PROCESS | 9981 | RSS: Main: 211714048 23:41:23 INFO - PROCESS | 9981 | 23:41:25 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51648/tests/kraken/driver.html?testName=json-parse-financial) 23:41:26 INFO - PROCESS | 9981 | RSS: Main: 211685376 23:41:26 INFO - PROCESS | 9981 | 23:41:27 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51648/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:41:28 INFO - PROCESS | 9981 | RSS: Main: 176590848 23:41:28 INFO - PROCESS | 9981 | 23:41:30 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:41:30 INFO - PROCESS | 9981 | RSS: Main: 182820864 23:41:30 INFO - PROCESS | 9981 | 23:41:32 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:41:33 INFO - PROCESS | 9981 | RSS: Main: 186900480 23:41:33 INFO - PROCESS | 9981 | 23:41:36 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:41:36 INFO - PROCESS | 9981 | RSS: Main: 181653504 23:41:36 INFO - PROCESS | 9981 | 23:41:39 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:41:39 INFO - PROCESS | 9981 | RSS: Main: 183713792 23:41:39 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | Cycle 1(1): loaded http://localhost:51648/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51648/tests/kraken/driver.html?testName=ai-astar) 23:41:41 INFO - PROCESS | 9981 | RSS: Main: 235909120 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | __start_tp_report 23:41:41 INFO - PROCESS | 9981 | _x_x_mozilla_page_load 23:41:41 INFO - PROCESS | 9981 | _x_x_mozilla_page_load_details 23:41:41 INFO - PROCESS | 9981 | |i|pagename|runs| 23:41:41 INFO - PROCESS | 9981 | |0;ai-astar;101;111;109;111;111;110;112;111;109;110 23:41:41 INFO - PROCESS | 9981 | |1;audio-beat-detection;144;114;115;165;120;116;139;116;157;116 23:41:41 INFO - PROCESS | 9981 | |2;audio-dft;161;160;160;162;162;161;160;161;161;161 23:41:41 INFO - PROCESS | 9981 | |3;audio-fft;75;74;74;114;75;77;75;76;112;74 23:41:41 INFO - PROCESS | 9981 | |4;audio-oscillator;83;82;120;84;83;83;117;83;82;137 23:41:41 INFO - PROCESS | 9981 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;117;117 23:41:41 INFO - PROCESS | 9981 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 23:41:41 INFO - PROCESS | 9981 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;96;95 23:41:41 INFO - PROCESS | 9981 | |8;json-parse-financial;49;50;49;50;49;50;50;50;57;50 23:41:41 INFO - PROCESS | 9981 | |9;json-stringify-tinderbox;61;74;61;61;62;62;60;61;61;62 23:41:41 INFO - PROCESS | 9981 | |10;stanford-crypto-aes;73;73;74;73;74;72;73;72;73;71 23:41:41 INFO - PROCESS | 9981 | |11;stanford-crypto-ccm;127;131;127;117;119;114;116;117;149;119 23:41:41 INFO - PROCESS | 9981 | |12;stanford-crypto-pbkdf2;154;152;152;154;149;152;151;150;152;149 23:41:41 INFO - PROCESS | 9981 | |13;stanford-crypto-sha256-iterative;64;61;62;62;60;66;61;62;60;60 23:41:41 INFO - PROCESS | 9981 | __end_tp_report 23:41:41 INFO - PROCESS | 9981 | __start_cc_report 23:41:41 INFO - PROCESS | 9981 | _x_x_mozilla_cycle_collect,1630 23:41:41 INFO - PROCESS | 9981 | __end_cc_report 23:41:41 INFO - PROCESS | 9981 | __startTimestamp1472107301677__endTimestamp 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | ------- Summary: start ------- 23:41:41 INFO - PROCESS | 9981 | Number of tests: 14 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 23:41:41 INFO - PROCESS | 9981 | Values: 101.0 111.0 109.0 111.0 111.0 110.0 112.0 111.0 109.0 110.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:129.50 stddev:19.44 (15.0%) stddev-sans-first:19.96 23:41:41 INFO - PROCESS | 9981 | Values: 144.0 114.0 115.0 165.0 120.0 116.0 139.0 116.0 157.0 116.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 23:41:41 INFO - PROCESS | 9981 | Values: 161.0 160.0 160.0 162.0 162.0 161.0 160.0 161.0 161.0 161.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.06 (21.4%) stddev-sans-first:16.79 23:41:41 INFO - PROCESS | 9981 | Values: 75.0 74.0 74.0 114.0 75.0 77.0 75.0 76.0 112.0 74.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:83.00 stddev:20.83 (25.1%) stddev-sans-first:21.61 23:41:41 INFO - PROCESS | 9981 | Values: 83.0 82.0 120.0 84.0 83.0 83.0 117.0 83.0 82.0 137.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:41:41 INFO - PROCESS | 9981 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 23:41:41 INFO - PROCESS | 9981 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 23:41:41 INFO - PROCESS | 9981 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:2.37 (4.7%) stddev-sans-first:2.46 23:41:41 INFO - PROCESS | 9981 | Values: 49.0 50.0 49.0 50.0 49.0 50.0 50.0 50.0 57.0 50.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:61.50 stddev:4.09 (6.6%) stddev-sans-first:4.30 23:41:41 INFO - PROCESS | 9981 | Values: 61.0 74.0 61.0 61.0 62.0 62.0 60.0 61.0 61.0 62.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:73.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 23:41:41 INFO - PROCESS | 9981 | Values: 73.0 73.0 74.0 73.0 74.0 72.0 73.0 72.0 73.0 71.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:123.00 stddev:10.55 (8.6%) stddev-sans-first:11.12 23:41:41 INFO - PROCESS | 9981 | Values: 127.0 131.0 127.0 117.0 119.0 114.0 116.0 117.0 149.0 119.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:152.00 stddev:1.78 (1.2%) stddev-sans-first:1.64 23:41:41 INFO - PROCESS | 9981 | Values: 154.0 152.0 152.0 154.0 149.0 152.0 151.0 150.0 152.0 149.0 23:41:41 INFO - PROCESS | 9981 | 23:41:41 INFO - PROCESS | 9981 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.93 (3.1%) stddev-sans-first:1.88 23:41:41 INFO - PROCESS | 9981 | Values: 64.0 61.0 62.0 62.0 60.0 66.0 61.0 62.0 60.0 60.0 23:41:41 INFO - PROCESS | 9981 | -------- Summary: end -------- 23:41:41 INFO - PROCESS | 9981 | 23:41:42 INFO - TEST-INFO | 9981: exit 0 23:41:42 INFO - TEST-OK | kraken | took 60299ms 23:41:42 INFO - SUITE-END | took 1173s 23:41:42 INFO - Completed test suite (00:19:34) 23:41:42 INFO - javascript benchmark 23:41:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3270.8855190068343, 3271.1985563536923, 3290.0458866928593, 3320.6347301796814, 3334.3998080345546, 5703.287089113861, 5795.71021448928, 5795.797147099853, 5810.477057064863, 5817.476427121558, 5759.769609215637, 5798.246227990366, 5813.622114562561, 5814.680192589406, 5824.592278540504, 5139.511746384106, 5200.245964335169, 5239.973800130994, 5298.947010529884, 5334.946650533484, 5282.471752824706, 5329.173978033411, 5334.066538355792, 5338.0, 5342.626016178869, 5159.458256883029, 5198.870028249286, 5212.0, 5212.5308722214995, 5251.028559716446, 5286.5506431952945, 5291.391489978663, 5319.228711836762, 5322.627416080876, 5353.544948679367, 5040.949590504121, 5114.309568208281, 5139.922901156486, 5216.765245563959, 5222.895542089137, 5674.404187560288, 5715.571332150063, 5716.228309178249, 5736.110902810071, 5737.3688894221605, 6189.9381006189815, 6201.689915504252, 6214.067889816519, 6219.191505104308, 6220.813375598739, 5986.760529578812, 6110.450059494622, 6129.049997250433, 6130.184124957599, 6242.282137554194, 5781.161731548902, 5787.045137552299, 5791.449812267828, 5812.534997200214, 5816.651000939957, 6267.561270711098, 6318.968405157945, 6338.112664227012, 6344.809655710336, 6348.333424990309, 5911.467967882912, 6004.0, 6033.5474839387225, 6038.456538911519, 6108.6029408089225, 6190.3190649028575, 6210.72051757672, 6215.595986260879, 6216.7824126155365, 6219.751209951552, 6149.108379284979, 6162.26052873658, 6162.661053642093, 6163.630182189083, 6164.599301045418, 5969.641821490724, 6013.6993150342305, 6048.274207095177, 6099.3900609938655, 6112.083187521818, 4651.930221046687, 4702.600278976257, 4709.552592503706, 4726.385569875963, 4741.762911854463, 4177.352510360899, 4194.182134483807, 4199.223143718422, 4202.03353228753, 4227.492700875914, 5729.5702822288495, 5840.532757379399, 5846.473817356458, 5849.356570777211, 5914.083317085859, 6227.06594010893, 6240.282367527701, 6245.156903818017, 6246.656433896089, 6250.499960003189, 5925.170476133345, 5941.405859414024, 5941.435563621449, 5941.6493181795095, 5977.820665380045, 5192.870178245498, 5273.947260527422, 5322.600804939645, 5361.651492652965, 5367.275417818544, 6397.776077837253, 6433.613983161025, 6434.549581529342, 6444.226692796894, 6454.838629034218, 6203.689815509297, 6267.341929097379, 6268.7492500301305, 6270.0908368287255, 6272.937270627235, 6188.381161883775, 6245.7189426476825, 6332.588381755171, 6395.488360931207, 6465.612063276219, 6084.482818960438, 6085.322139185672, 6102.511799056154, 6106.297775755845, 6134.4172303631085, 6161.4146656067605, 6187.690615469119, 6193.287771906288, 6195.752169913333, 6199.814005579839, 5839.649621022752, 5998.0103282957825, 6033.909491357545, 6072.1781571088995, 6107.053406721965, 6023.277206735044, 6060.151578778887, 6065.636061836304, 6080.908786368296, 6107.816765497042, 5962.612430192024, 6010.459058684739, 6019.398060193946, 6024.186734790747, 6033.87932241344, 5995.2206213192, 6012.969935150471, 6034.939650603614, 6096.146539484387, 6099.993501072273, 5926.407359264039, 5926.762929482945, 5932.525397968066, 5933.2880054393745, 5950.583459157818], "value": 5691.83800458774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [600.0968542343772, 643.8261669349275, 668.8695704337653, 676.435176627516, 680.4182424027454, 34698.479522807145, 37872.24255514888, 38114.23771524575, 38182.42726359103, 47196.76401617987, 43795.0, 47826.282605760854, 47891.28163077548, 48004.51995480044, 48042.27936580954, 8991.865122023159, 9193.770155746126, 9194.448333100003, 9194.540272986358, 9195.770105747342, 8338.207870252316, 8548.230659240668, 9063.13900179482, 9153.816923661523, 9157.587908544097, 10875.293105948089, 11189.49647265875, 11198.160137989642, 11533.884661153406, 11541.0, 10905.890941090567, 11002.669919902415, 11062.668119956414, 11067.335959842396, 11067.72330691731, 10778.191635627281, 10868.782624347468, 10872.67381978534, 10873.238873278873, 10875.728106797314, 44423.0, 44747.10505789866, 44801.0, 44893.32660010103, 44898.77550612258, 3850.268448994682, 4273.215014482955, 4480.14877173336, 4594.931076033862, 4715.504871988461, 34350.65649343524, 34510.82744586286, 35680.464793027866, 35771.4634280486, 36387.0, 4555.339475776027, 4596.195665758512, 4599.724016559017, 4681.9297710534365, 4829.396325459317, 390722.0, 390999.0, 391553.0422347899, 392248.0, 392377.0, 64669.67665161738, 65137.0, 65144.348556513825, 65183.34816651868, 65273.67363163154, 39836.0, 40712.0, 40833.59166408298, 42217.0, 66928.33071669222, 19823.30618428377, 20039.0, 20053.49866253326, 20061.899690501745, 20077.0, 15051.698966020836, 15122.848771512365, 15130.394784208509, 15132.924335378473, 15136.772948405784, 19032.61934761297, 20198.192072317237, 20684.896575517025, 20964.79035209659, 21044.47388815292, 7401.59291238987, 7553.622318884034, 7642.159362470124, 7773.3004029637605, 7877.369810415153, 1036.7356324137297, 1108.5898217659517, 1117.1342209787479, 1142.5772464188303, 1239.0521251242806, 33188.668113318556, 35403.468947965805, 35684.82157589247, 36818.07954801151, 38289.0, 1194.6057800925673, 1202.801537746262, 1221.322166197751, 1222.1689251309194, 1241.3855141704914, 18085.457436276934, 18130.728039079426, 18243.310454590617, 18308.633827323378, 18406.447806565826, 668933.0, 678798.0, 679797.0, 680007.0, 680039.0, 62800.0, 63124.0, 63271.36728632747, 63288.0, 63392.68303658452, 1407.0, 1419.84381718013, 1456.3810380588206, 1506.7363211438173, 1543.6063803729976, 34227.65772342344, 35778.64221357753, 36061.81969090138, 36121.0, 36345.454818178274, 53469.19796202982, 54124.729376352865, 54161.0, 54209.0, 54276.0, 491368.0, 495290.0, 496558.0, 496877.0, 497738.0, 29172.708272917847, 30149.246268842577, 30168.09495715132, 30173.849130754206, 30204.697953021066, 49704.75147624239, 50587.49412505975, 51083.74458127686, 51116.74441627768, 51136.74431627818, 42734.0, 43278.783606081764, 43320.56679433165, 44052.0, 44406.0, 41951.7902410486, 42211.577884221995, 42470.362944555236, 42917.1416571673, 43595.0, 42890.35664465098, 43347.34978975381, 44753.0, 44812.10375792401, 44869.10261794811, 35953.0, 38015.23969520539, 38837.6116238834, 38960.610393895695, 39136.412953806284, 29465.0, 29622.407551849272, 29647.407051858412, 29928.0, 29984.25039374033], "value": 21901.04984732635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.2719900187149, 416.2278972506001, 420.9074003719182, 423.7817523975153, 445.427625501231, 15183.620409489768, 20307.898460507695, 20339.389818305437, 20509.384718458452, 20824.0, 19847.40457786265, 19861.602767944667, 20125.39623811288, 20174.596508069866, 20215.191392344324, 5710.7715691372505, 5794.797182098628, 5867.354590994987, 5907.409259074079, 5913.704314784265, 5872.060470324749, 5873.765049398019, 5901.557383196277, 5906.586538942276, 5907.0, 5713.514351280146, 5891.793787217448, 5919.408059194067, 5924.200232968558, 5929.495992840592, 5714.028615135396, 5746.138079288098, 5859.179714839905, 5896.174535565025, 5908.497777688902, 5184.662996905189, 5242.580593552545, 5242.68543887368, 5246.580273578143, 5247.291615631879, 23267.3019809406, 23457.53084938309, 23573.29280121599, 23597.7640223599, 23612.17357392483, 2434.267838758208, 2619.1356852238905, 2693.6498255226893, 2767.0, 3063.9234019149476, 14746.336414861356, 14900.776488352683, 14960.925195374062, 15513.991590546579, 15663.530094097081, 2016.2136766661015, 2017.2233690029298, 2025.0887100804696, 2036.6232247034345, 2095.8323334133383, 82321.78963004147, 82435.0, 82929.0, 83077.0, 84404.15595844085, 10898.94550527258, 10974.725631859268, 11207.327560346404, 11341.603043893425, 11529.769404611861, 15775.921120394325, 15797.684046319238, 15813.841861581466, 15831.920840395724, 15857.682846343008, 8590.355723320776, 8889.688860889837, 8899.822003559893, 8958.462492250486, 9012.774680633036, 8767.342449316327, 8775.561221938877, 8844.557772111497, 9011.414258073204, 9029.097090290918, 6792.898106528406, 6804.863902722017, 6892.517523773287, 6969.4424446044195, 6983.511154219156, 2034.6846238833004, 2078.3349328215127, 2080.5318803269142, 2082.5938941906184, 2116.7248257726396, 962.764122789921, 1059.06802014227, 1066.626680661762, 1073.8067147913293, 1093.7858976536038, 20331.695024574645, 20727.481812954797, 20737.0, 20747.68878466824, 21020.264290750056, 1087.6138970665459, 1090.432974853072, 1107.9556817727362, 1122.141561705296, 1128.1820680006929, 10947.726306842393, 11746.689935315268, 11914.0, 11952.880471195176, 11996.580119695944, 498637.0, 515923.0, 531319.0, 532601.0, 535037.0, 83025.58487207525, 86219.56890215634, 87617.0, 87693.0, 88439.90460333986, 1090.9399983000853, 1102.9191392435298, 1107.2858006585707, 1109.7392112853472, 1120.6301920366259, 16551.0, 18263.933248163823, 18450.538736531693, 18586.721199181753, 18587.535311617317, 41841.79079104707, 42023.0, 42735.7863210682, 42764.0, 43680.0, 56649.150262745265, 57148.0, 57460.42539574718, 57934.7103264481, 59116.704416477645, 8442.915570844458, 8653.653853845775, 8663.653453861774, 8815.559222038744, 9034.051424600322, 12354.0, 13162.0, 13807.516736914562, 13862.306884655525, 14223.715525689635, 11979.401029948642, 12307.56923507673, 12348.3825808711, 12436.316002619942, 12560.37198140071, 11547.884521155018, 11558.0, 11723.41382930867, 12013.939930300292, 12373.443195056065, 11799.82300265514, 12192.634220973385, 12317.876821231674, 12362.258264504158, 12416.0, 9206.49364284944, 9262.0, 9341.906580934103, 9348.766280843034, 9416.623335066795, 11418.82871756941, 11554.0, 11625.0, 11748.0, 11943.940280298542], "value": 9794.190841127021, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.4274647394557, 1536.9077855328678, 1617.2479796894443, 1789.2127463915876, 1939.0692467615536, 9344.953275233624, 9781.070798274162, 9803.117719405253, 10086.193104551636, 10090.747731306721, 9847.21222302216, 9960.402375857453, 9960.551775170117, 9964.900350996488, 9970.202383809295, 2525.83582067166, 2535.9746402536016, 2548.872556372174, 2555.9361015974655, 2559.5392829290813, 2453.0678342229926, 2481.1192026830486, 2493.0277191895175, 2493.7007559092845, 2495.663085483455, 2454.9386265343333, 2457.6559281700575, 2519.143491212987, 2521.10500772226, 2526.12848567244, 2433.0754313360985, 2433.987830060847, 2446.412860913377, 2487.8009759219217, 2496.6255061740885, 2415.40822498489, 2424.3090719145134, 2429.817763667732, 2436.427439551704, 2486.465409936854, 9777.90222097777, 9919.851202231972, 9956.303058785888, 9970.800583988279, 9978.700638980843, 5684.346300175492, 5902.822915312548, 5907.5864689471755, 5914.822555323347, 5917.082852157922, 3590.7666001710068, 3737.663610275088, 3738.0, 3792.9620703792884, 3807.7144214184045, 1243.9129260951736, 1262.6111277594664, 1267.3283159925254, 1308.5223893278885, 1328.6479083042905, 14234.92882535591, 14376.712465750627, 14377.71244575113, 14385.856141438557, 14390.568282951528, 4831.661783675109, 4860.222364421676, 4967.155583550805, 5028.5977121830165, 5080.974595127074, 11035.668929932115, 11051.447427628586, 11053.336799792038, 11065.668029959113, 11067.225294229487, 5858.326292476335, 5859.44335288147, 5861.120831875252, 5862.325832529228, 5866.6480011199465, 4596.37948876904, 5203.8178663746585, 5241.659292145999, 5315.04329220744, 5440.265564148789, 4104.727731556317, 4155.667546596265, 4204.831806727697, 4239.97880010598, 4246.341817018367, 1222.3032871263295, 1223.1193540650718, 1226.45422786859, 1227.594404406967, 1247.1207798502035, 915.8809354783969, 975.8975307592742, 1022.8056669232822, 1029.351508549609, 1048.7483004078965, 6275.497960163176, 6342.587731797418, 6402.903956440658, 6454.386833250834, 6725.05849181115, 491.7516654089662, 492.31076492909494, 496.659788045183, 499.9750012499433, 502.1037448155026, 4535.387722657399, 4642.396488456479, 4671.8787491002595, 4765.73788441639, 4790.568848803624, 12183.147179697513, 12563.057770667236, 12576.748465030649, 12581.559645412366, 12669.809952850715, 11245.212835101627, 11655.708607284885, 11670.941645291889, 12110.515579376726, 12503.499860005499, 9106.86339704905, 9290.16388525036, 9321.72034838956, 9333.813323733488, 9345.15893569582, 6936.965315173392, 7217.711291548279, 7233.421326293989, 7257.600831954293, 7278.854422911406, 11481.770364592829, 11533.423328833356, 11693.473793679475, 11699.12256580777, 11790.41047947582, 11555.4800033997, 11641.47613357384, 11674.124440666814, 11976.820347694616, 11990.160688752052, 7676.6161691916295, 8547.487150770974, 8584.270337021424, 8712.91287087121, 8781.473111613324, 9604.711858644252, 9659.758506037124, 9663.613455461702, 9685.370450920667, 9691.466969316472, 8421.410501264852, 8464.957675211585, 8474.533900635524, 8482.745517634481, 8484.957575212084, 8332.375071869512, 8346.415750897379, 8423.873641895254, 8433.746987590383, 8447.239748422671, 7579.0905091387185, 7649.196834332315, 7649.388048956182, 7654.0, 7729.111152217342, 5925.792597259076, 5981.0, 6004.42957918997, 6025.18659980897, 6227.844303892439, 5827.912581311369, 5832.0, 5844.766209351578, 5911.615744976562, 6051.909221361771], "value": 5019.468422644065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.62301844952106, 351.42366518908995, 352.1794219468637, 367.8804388573714, 367.9172186258091, 5296.232046353276, 5447.645903016311, 5470.917936230955, 5533.391326954037, 5542.75057622407, 5438.401775804658, 5461.15352120421, 5467.441779092949, 5471.768852008297, 5481.287432633762, 4359.542248063955, 4362.149380870724, 4364.803583838719, 4367.956320436787, 4370.38814565961, 4149.609880689973, 4328.502222244435, 4364.629006534448, 4369.344598310263, 4372.497162826268, 4294.67789915756, 4323.286657701476, 4336.501302350222, 4347.760873151976, 4352.107817897339, 4320.200762858881, 4335.826566937319, 4337.175936572041, 4339.609435150852, 4360.454943132077, 3626.782393056425, 3882.3594106972596, 3884.6503814656817, 3886.3393223152134, 3892.1826416452577, 5337.919931201035, 5343.812966546191, 5356.901835123829, 5366.517013468807, 5377.677339359651, 4951.702897826142, 4962.106820772259, 4965.280034395028, 4969.428515720702, 4974.701517908937, 5472.398036216013, 5694.430556944314, 5744.3394009689, 5747.913781293284, 5762.30852297727, 4153.979230103831, 4168.520620128664, 4168.958310416887, 4183.6234738873345, 4199.7531320743565, 5905.793297234576, 5953.136795164677, 5957.344692083867, 5965.940340596582, 5972.671503067286, 4178.7283826551175, 4244.257254980366, 4257.914841703148, 4277.914441711148, 4278.871633850989, 4307.1601037797955, 4309.547497512779, 4331.393604895317, 4334.891627709332, 4335.978320108379, 4910.017996400663, 4917.065757506062, 4917.385326834145, 4918.311436398907, 4925.285833554115, 5439.347278326626, 5712.80005199816, 5762.674584845509, 5777.913331300034, 5781.653100813881, 1450.724362371146, 1464.4947493114857, 1473.710503309604, 1478.0, 1481.9333130009175, 906.7914379692708, 946.8674385586023, 958.6357184269933, 973.9415635061778, 975.9170470509944, 5020.472850350734, 5021.020900924284, 5032.220005899091, 5036.370453693288, 5040.0, 5219.982103489783, 5237.292965449692, 5260.684358938476, 5262.368665713641, 5266.4470230626, 4979.775910084055, 4984.476629953875, 5010.223415370623, 5299.701573114611, 5688.288963879515, 3133.9843300783805, 3157.41587806252, 3162.3517178978254, 3166.82582457967, 3169.984150079235, 6062.757489700451, 6077.848053798691, 6087.421694938974, 6093.482054025458, 6110.419510146529, 5796.942030579725, 6042.425969532887, 6061.030235162353, 6085.6044357117535, 6090.299615544181, 6142.0, 6148.907766383508, 6153.2000839890525, 6154.907676384858, 6161.322254551996, 5697.401772813795, 5870.7945221918735, 5902.439268269508, 5954.642721436728, 5974.641521508724, 5829.8251052468495, 5869.911951320648, 5872.911906321323, 5882.352941176381, 5884.558658100574, 5845.503132233808, 6161.537884658579, 6162.907556386568, 6176.907346389898, 6190.226221722285, 4200.621944024931, 4255.723377980421, 4259.510156332061, 4261.701680882308, 4271.163399738142, 5933.139694744152, 5940.316863560746, 5946.583739138392, 5976.402359763989, 5978.372270911492, 5674.687892165805, 5791.333996590446, 5840.94159058404, 5855.23881895368, 5862.530997520274, 5809.651420914759, 5809.883802324014, 5833.154192642128, 5837.620554663933, 5842.1820945066875, 5765.509931655856, 5770.826875193751, 5770.8845823082465, 5781.762213160643, 5794.62334948227, 5622.24099746529, 5808.854778630568, 5815.185873977731, 5822.0, 5827.854303642443, 5687.976164290302, 5690.430956904442, 5691.975444420044, 5694.686792226467, 5711.914321285267], "value": 4386.477566233149, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.3452430909418, 779.1507257089771, 801.4870482890951, 831.3681601982491, 876.9914598212051, 17113.743293850588, 17372.826271737296, 17692.911535442337, 17856.0, 17885.910570447162, 16332.918335408307, 16474.176291185453, 16492.17539123042, 16498.25757840897, 16510.83489165108, 758.2341834746918, 880.1286726141107, 904.6833608237097, 910.2354022620997, 926.5691453474133, 889.1020069729587, 898.1512470715177, 898.5417437107058, 899.3929097858952, 899.9730008099767, 932.8460803967337, 935.7988032572996, 937.386012162035, 937.5734041011358, 937.7843096087904, 783.2872086401347, 921.6083164654995, 928.0487500312166, 930.2743859789375, 937.4890684576922, 684.910961574992, 692.7748481743454, 696.8501772119018, 696.8571442854253, 697.1982220446527, 15264.084154950739, 15315.770263445946, 15428.768568471482, 15520.223988800628, 15559.37762489499, 15114.697706045927, 15505.689886202326, 15669.843301567067, 15837.683246335124, 15975.520734377988, 13963.441462341494, 13965.58103256904, 14081.366338514792, 14083.225422601752, 14127.223002734845, 4630.282306242538, 4644.027822193799, 4650.511696271877, 4729.9763501182615, 4738.549837765441, 81609.0, 84503.0, 85111.57444212801, 85736.57131714364, 86544.13455865423, 12248.938755306166, 12366.44351004207, 12438.19151755133, 12589.622311330675, 12604.936975315248, 22652.207172749204, 22778.20276290322, 22846.0, 23001.654975175385, 23583.0, 8918.37571369998, 8947.150020748019, 9031.548422578844, 9032.412893161923, 9539.09378609031, 5100.438951715309, 5188.11801993662, 5274.841754747364, 5304.204369344552, 5307.575393968474, 3848.5189351331082, 3988.4216788565495, 4006.979965100156, 4016.357382818735, 4024.959750402517, 686.842026333946, 693.7962634828568, 698.3819319901924, 704.9612271325126, 747.8018325143842, 720.020771750419, 723.5152447859947, 768.7155752371658, 812.1878121878121, 825.0511911302051, 10291.897081029243, 10859.294145880493, 10867.34795912232, 10983.725406864733, 11032.393218373067, 683.5420268420171, 685.338648204485, 767.7005967672612, 790.3992965346379, 805.7260531419346, 5092.9236061459105, 5384.111621582473, 5387.24578559002, 5416.674999500043, 5422.75597598109, 74799.62600186965, 81651.18348816436, 134463.0, 134521.0, 134741.0, 113936.43031784787, 114874.0, 115166.0, 118433.0, 120437.39781301038, 86330.0, 100049.0, 101591.0, 102670.0, 105039.47480262702, 10047.0, 10228.386296822215, 10269.383836969806, 10582.682519524427, 10648.8935110651, 56840.71579642075, 57080.714596426755, 58481.122783157436, 59859.40140598538, 59953.700231498566, 79679.60160199161, 87297.56351218204, 88348.55825720831, 90359.54820225856, 93818.53090734503, 12948.15836970594, 13304.46782128704, 13308.268045257604, 13349.332533373099, 13407.463701452223, 14916.925415372854, 15110.320035598645, 15256.160911149993, 15393.599976041143, 16126.435574754827, 13018.80471792905, 13405.128666636638, 13459.932700336436, 13515.459381624625, 13673.658158545719, 13280.203187808762, 13487.123336983064, 13500.0, 13606.659833504242, 14243.928780356446, 14378.28108594587, 14422.495212667507, 14425.711485770436, 15321.157336346609, 15563.910526263053, 10646.361218326925, 11144.888551114385, 11287.604933827277, 11555.826662600235, 11685.0, 11342.546298147981, 11881.762364752829, 12820.294883781482, 13465.326733663474, 13833.723325533634], "value": 8254.659090614, "unit": "score"}], "name": "dromaeo_css", "value": 7780.970229207874, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 109.0, 111.0, 111.0, 110.0, 112.0, 111.0, 109.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 115.0, 165.0, 120.0, 116.0, 139.0, 116.0, 157.0, 116.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 162.0, 161.0, 160.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 114.0, 75.0, 77.0, 75.0, 76.0, 112.0, 74.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 120.0, 84.0, 83.0, 83.0, 117.0, 83.0, 82.0, 137.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 49.0, 50.0, 50.0, 50.0, 57.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 74.0, 61.0, 61.0, 62.0, 62.0, 60.0, 61.0, 61.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 73.0, 74.0, 72.0, 73.0, 72.0, 73.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 127.0, 117.0, 119.0, 114.0, 116.0, 117.0, 149.0, 119.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 152.0, 154.0, 149.0, 152.0, 151.0, 150.0, 152.0, 149.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 62.0, 62.0, 60.0, 66.0, 61.0, 62.0, 60.0, 60.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1459.2999999999997, "alertThreshold": 2.0}]} 23:41:42 INFO - Return code: 0 23:41:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:41:42 INFO - # TBPL SUCCESS # 23:41:42 INFO - Running post-action listener: _resource_record_post_action 23:41:42 INFO - [mozharness: 2016-08-25 06:41:42.993174Z] Finished run-tests step (success) 23:41:42 INFO - Running post-run listener: _resource_record_post_run 23:41:43 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 326463488; Read time: 8; Write time: 295964 23:41:43 INFO - TinderboxPrint: CPU usage
12.9% 23:41:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:41:43 INFO - TinderboxPrint: I/O write bytes / time
326,463,488 / 295,964 23:41:43 INFO - TinderboxPrint: CPU idle
8,275.5 (87.1%) 23:41:43 INFO - TinderboxPrint: CPU user
1,182.6 (12.4%) 23:41:43 INFO - TinderboxPrint: Swap in / out
0 / 0 23:41:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79429632; Read time: 0; Write time: 101940 23:41:43 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 246603776; Read time: 0; Write time: 193916 23:41:43 INFO - Running post-run listener: _upload_blobber_files 23:41:43 INFO - Blob upload gear active. 23:41:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:41:43 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 23:41:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:41:44 INFO - (blobuploader) - INFO - Open directory for files ... 23:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:41:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:41:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:41:44 INFO - (blobuploader) - INFO - Done attempting. 23:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:41:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:41:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:41:49 INFO - (blobuploader) - INFO - Done attempting. 23:41:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:41:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:41:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:41:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:41:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:41:49 INFO - (blobuploader) - INFO - Done attempting. 23:41:49 INFO - (blobuploader) - INFO - Iteration through files over. 23:41:49 INFO - Return code: 0 23:41:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:41:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:41:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef5ee438c51dec8bae06812b317c9ddb3d2504cd7f8a8e6b8b9e880dd69318a66d5799be5033c832a7b2497954f553c870892899bdae5f7a3198e06d46905aa1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1518a957e0c1e68dad1852ecd5d9d6102d18ed1c622a55dda2db6e9a07cdc1c63e3d8496d4a784d2abe320da3be866dc726c553c41624adc4ae3f7c26266d5c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/433dc3fb39607cbfac0517ce4f68ab2656b967ec55a1315fe492eb8683849896ad01f7fde48d536f6ef77502012f5a5fbd69391b8a624a556f0fcc65b6c60c5f"} 23:41:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:41:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:41:49 INFO - Contents: 23:41:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef5ee438c51dec8bae06812b317c9ddb3d2504cd7f8a8e6b8b9e880dd69318a66d5799be5033c832a7b2497954f553c870892899bdae5f7a3198e06d46905aa1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1518a957e0c1e68dad1852ecd5d9d6102d18ed1c622a55dda2db6e9a07cdc1c63e3d8496d4a784d2abe320da3be866dc726c553c41624adc4ae3f7c26266d5c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/433dc3fb39607cbfac0517ce4f68ab2656b967ec55a1315fe492eb8683849896ad01f7fde48d536f6ef77502012f5a5fbd69391b8a624a556f0fcc65b6c60c5f"} 23:41:49 INFO - Running post-run listener: copy_logs_to_upload_dir 23:41:49 INFO - Copying logs to upload dir... 23:41:49 INFO - mkdir: /builds/slave/test/build/upload/logs 23:41:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.988855 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-24 23:41:49.157267) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:41:49.160415) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef5ee438c51dec8bae06812b317c9ddb3d2504cd7f8a8e6b8b9e880dd69318a66d5799be5033c832a7b2497954f553c870892899bdae5f7a3198e06d46905aa1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1518a957e0c1e68dad1852ecd5d9d6102d18ed1c622a55dda2db6e9a07cdc1c63e3d8496d4a784d2abe320da3be866dc726c553c41624adc4ae3f7c26266d5c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/433dc3fb39607cbfac0517ce4f68ab2656b967ec55a1315fe492eb8683849896ad01f7fde48d536f6ef77502012f5a5fbd69391b8a624a556f0fcc65b6c60c5f"} build_url:https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/KmSxuhGVR9CTEZ5Era6oKg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef5ee438c51dec8bae06812b317c9ddb3d2504cd7f8a8e6b8b9e880dd69318a66d5799be5033c832a7b2497954f553c870892899bdae5f7a3198e06d46905aa1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1518a957e0c1e68dad1852ecd5d9d6102d18ed1c622a55dda2db6e9a07cdc1c63e3d8496d4a784d2abe320da3be866dc726c553c41624adc4ae3f7c26266d5c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/433dc3fb39607cbfac0517ce4f68ab2656b967ec55a1315fe492eb8683849896ad01f7fde48d536f6ef77502012f5a5fbd69391b8a624a556f0fcc65b6c60c5f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 23:41:49.185634) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:41:49.186019) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1472105400.751654-183181153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 23:41:49.270167) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:41:49.270651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 23:41:49.271169) ========= ========= Total master_lag: 0.15 =========