builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1472186151.0 results: success (0) buildid: 20160825171918 builduid: fc95ac9ba91344d49e56d2ec42e056d0 revision: 458d5f0a55bbb2423091a2108cab633321a64d26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:50.998972) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:50.999457) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:50.999727) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.015163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.015436) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 21:35:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-08-25 21:35:51 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129865 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.159065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.159379) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017967 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.210909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.211273) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 458d5f0a55bbb2423091a2108cab633321a64d26 --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 458d5f0a55bbb2423091a2108cab633321a64d26 --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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 21:35:51,477 truncating revision to first 12 chars 2016-08-25 21:35:51,477 Setting DEBUG logging. 2016-08-25 21:35:51,477 attempt 1/10 2016-08-25 21:35:51,477 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/458d5f0a55bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 21:35:51,738 unpacking tar archive at: mozilla-aurora-458d5f0a55bb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.539460 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.764356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.764666) ========= script_repo_revision: 458d5f0a55bbb2423091a2108cab633321a64d26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.765073) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.765365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 21:35:51.776339) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 29 mins, 12 secs) (at 2016-08-25 21:35:51.776744) ========= /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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:35:52 INFO - MultiFileLogger online at 20160825 21:35:52 in /builds/slave/test 21:35:52 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 21:35:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:35:52 INFO - {'append_to_log': False, 21:35:52 INFO - 'base_work_dir': '/builds/slave/test', 21:35:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:35:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:35:52 INFO - 'branch': 'Mozilla-Aurora', 21:35:52 INFO - 'buildbot_json_path': 'buildprops.json', 21:35:52 INFO - 'config_files': ('talos/linux_config.py',), 21:35:52 INFO - 'default_actions': ('clobber', 21:35:52 INFO - 'read-buildbot-config', 21:35:52 INFO - 'download-and-extract', 21:35:52 INFO - 'populate-webroot', 21:35:52 INFO - 'create-virtualenv', 21:35:52 INFO - 'install', 21:35:52 INFO - 'run-tests'), 21:35:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:35:52 INFO - 'download_minidump_stackwalk': True, 21:35:52 INFO - 'download_symbols': 'ondemand', 21:35:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:35:52 INFO - 'tooltool.py': '/tools/tooltool.py', 21:35:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:35:52 INFO - '/tools/misc-python/virtualenv.py')}, 21:35:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:35:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:35:52 INFO - 'installer_path': 'installer.exe', 21:35:52 INFO - 'log_level': 'info', 21:35:52 INFO - 'log_name': 'talos', 21:35:52 INFO - 'log_to_console': True, 21:35:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:35:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:35:52 INFO - 'opt_config_files': (), 21:35:52 INFO - 'pip_index': False, 21:35:52 INFO - 'sps_profile': False, 21:35:52 INFO - 'sps_profile_interval': 0, 21:35:52 INFO - 'suite': 'dromaeojs', 21:35:52 INFO - 'system_bits': '32', 21:35:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:35:52 INFO - 'title': 'talos-linux64-ix-011', 21:35:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:35:52 INFO - 'use_talos_json': True, 21:35:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:35:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:35:52 INFO - 'work_dir': 'build'} 21:35:52 INFO - [mozharness: 2016-08-26 04:35:52.130650Z] Running clobber step. 21:35:52 INFO - Running pre-action listener: _resource_record_pre_action 21:35:52 INFO - Running main action method: clobber 21:35:52 INFO - rmtree: /builds/slave/test/build 21:35:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:35:52 INFO - Running post-action listener: _resource_record_post_action 21:35:52 INFO - [mozharness: 2016-08-26 04:35:52.597146Z] Finished clobber step (success) 21:35:52 INFO - [mozharness: 2016-08-26 04:35:52.597262Z] Running read-buildbot-config step. 21:35:52 INFO - Running pre-action listener: _resource_record_pre_action 21:35:52 INFO - Running main action method: read_buildbot_config 21:35:52 INFO - Using buildbot properties: 21:35:52 INFO - { 21:35:52 INFO - "project": "", 21:35:52 INFO - "product": "firefox", 21:35:52 INFO - "script_repo_revision": "production", 21:35:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 21:35:52 INFO - "repository": "", 21:35:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 21:35:52 INFO - "buildid": "20160825171918", 21:35:52 INFO - "pgo_build": "True", 21:35:52 INFO - "basedir": "/builds/slave/test", 21:35:52 INFO - "buildnumber": 494, 21:35:52 INFO - "slavename": "talos-linux64-ix-011", 21:35:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:35:52 INFO - "platform": "ubuntu64_hw", 21:35:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:35:52 INFO - "branch": "mozilla-aurora", 21:35:52 INFO - "repo_path": "releases/mozilla-aurora", 21:35:52 INFO - "revision": "458d5f0a55bbb2423091a2108cab633321a64d26", 21:35:52 INFO - "stage_platform": "linux64-pgo", 21:35:52 INFO - "builduid": "fc95ac9ba91344d49e56d2ec42e056d0", 21:35:52 INFO - "slavebuilddir": "test" 21:35:52 INFO - } 21:35:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 21:35:52 INFO - Running post-action listener: _resource_record_post_action 21:35:52 INFO - [mozharness: 2016-08-26 04:35:52.599784Z] Finished read-buildbot-config step (success) 21:35:52 INFO - [mozharness: 2016-08-26 04:35:52.599874Z] Running download-and-extract step. 21:35:52 INFO - Running pre-action listener: _resource_record_pre_action 21:35:52 INFO - Running main action method: download_and_extract 21:35:52 INFO - mkdir: /builds/slave/test/build/tests 21:35:52 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')]} 21:35:52 INFO - https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:35:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:35:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:35:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:35:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:35:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:35:52 INFO - retry: Failed, sleeping 30 seconds before retrying 21:36:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:36:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:36:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:36:22 INFO - retry: Failed, sleeping 60 seconds before retrying 21:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:37:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:37:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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! 21:37:22 INFO - Caught exception: HTTP Error 404: Not Found 21:37:22 INFO - Caught exception: HTTP Error 404: Not Found 21:37:22 INFO - Caught exception: HTTP Error 404: Not Found 21:37:22 INFO - trying https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:23 INFO - Downloaded 1361 bytes. 21:37:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 21:37:23 INFO - Using the following test package requirements: 21:37:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:37:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:37:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:37:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'jsshell-linux-x86_64.zip'], 21:37:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:37:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 21:37:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:37:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:37:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:37:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 21:37:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:37:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:37:23 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')]} 21:37:23 INFO - https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:37:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:37:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:37:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:37:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:37:23 INFO - retry: Failed, sleeping 30 seconds before retrying 21:37:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:37:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:37:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:37:53 INFO - retry: Failed, sleeping 60 seconds before retrying 21:38:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:38:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:38:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:38:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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! 21:38:53 INFO - Caught exception: HTTP Error 404: Not Found 21:38:53 INFO - Caught exception: HTTP Error 404: Not Found 21:38:53 INFO - Caught exception: HTTP Error 404: Not Found 21:38:53 INFO - trying https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:38:53 INFO - Downloading https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:38:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:38:54 INFO - Downloaded 21768605 bytes. 21:38:54 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'] 21:38:54 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 21:38:55 INFO - Return code: 0 21:38:55 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 21:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:38:55 INFO - https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:38:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:38:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:38:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:38:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:38:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:38:55 INFO - retry: Failed, sleeping 30 seconds before retrying 21:39:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:39:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:39:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:39:25 INFO - retry: Failed, sleeping 60 seconds before retrying 21:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:40:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:40:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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! 21:40:25 INFO - Caught exception: HTTP Error 404: Not Found 21:40:25 INFO - Caught exception: HTTP Error 404: Not Found 21:40:25 INFO - Caught exception: HTTP Error 404: Not Found 21:40:25 INFO - trying https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 21:40:25 INFO - Downloading https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:26 INFO - Downloaded 21768605 bytes. 21:40:26 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'] 21:40:26 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 21:40:27 INFO - Return code: 0 21:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:40:27 INFO - https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:40:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:40:27 INFO - retry: Failed, sleeping 30 seconds before retrying 21:40:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:40:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:40:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:40:57 INFO - retry: Failed, sleeping 60 seconds before retrying 21:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:41:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:41:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:41:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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! 21:41:57 INFO - Caught exception: HTTP Error 404: Not Found 21:41:57 INFO - Caught exception: HTTP Error 404: Not Found 21:41:57 INFO - Caught exception: HTTP Error 404: Not Found 21:41:57 INFO - trying https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 21:41:57 INFO - Downloading https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:41:58 INFO - Downloaded 14413723 bytes. 21:41:58 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'] 21:41:58 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 21:41:58 INFO - Return code: 0 21:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:41:58 INFO - https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:41:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:41:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:41:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:41:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:41:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:41:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:41:58 INFO - retry: Failed, sleeping 30 seconds before retrying 21:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:42:28 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:42:28 INFO - Running command: ['nslookup', 'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 21:42:28 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 21:42:28 INFO - Server: 10.26.75.40 21:42:28 INFO - Address: 10.26.75.40#53 21:42:28 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 21:42:28 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 21:42:28 INFO - Address: 10.26.48.46 21:42:28 INFO - Return code: 0 21:42:28 INFO - retry: attempt #2 caught exception: 21:42:28 INFO - retry: Failed, sleeping 60 seconds before retrying 21:43:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:43:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:43:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:43:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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! 21:43:29 INFO - Caught exception: HTTP Error 404: Not Found 21:43:29 INFO - Caught exception: 21:43:29 INFO - Caught exception: HTTP Error 404: Not Found 21:43:29 INFO - trying https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:43:29 INFO - Downloading https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:43:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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 21:43:31 INFO - Downloaded 63752159 bytes. 21:43:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:43:31 INFO - mkdir: /builds/slave/test/properties 21:43:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:43:31 INFO - Writing to file /builds/slave/test/properties/build_url 21:43:31 INFO - Contents: 21:43:31 INFO - build_url:https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 21:43:31 INFO - Running post-action listener: _resource_record_post_action 21:43:31 INFO - Running post-action listener: set_extra_try_arguments 21:43:31 INFO - [mozharness: 2016-08-26 04:43:31.798830Z] Finished download-and-extract step (success) 21:43:31 INFO - [mozharness: 2016-08-26 04:43:31.799063Z] Running populate-webroot step. 21:43:31 INFO - Running pre-action listener: _resource_record_pre_action 21:43:31 INFO - Running main action method: populate_webroot 21:43:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:43:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:43:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:43:31 INFO - '--tppagecycles', 21:43:31 INFO - '7'], 21:43:31 INFO - 'tests': ['tsvgm']}}, 21:43:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:43:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:43:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:43:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:43:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:43:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:43:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['damp', 'tps']}, 21:43:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'tests': ['damp', 'tps']}, 21:43:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['dromaeo_dom']}, 21:43:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:43:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['basic_compositor_video']}, 21:43:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:43:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'other-e10s': {'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['a11yr', 21:43:31 INFO - 'ts_paint', 21:43:31 INFO - 'tpaint', 21:43:31 INFO - 'sessionrestore', 21:43:31 INFO - 'sessionrestore_no_auto_restore', 21:43:31 INFO - 'tabpaint']}, 21:43:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['tsvgx', 21:43:31 INFO - 'tsvgr_opacity', 21:43:31 INFO - 'tart', 21:43:31 INFO - 'tscrollx', 21:43:31 INFO - 'cart']}, 21:43:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:43:31 INFO - 'tsvgr_opacity', 21:43:31 INFO - 'tart', 21:43:31 INFO - 'tscrollx', 21:43:31 INFO - 'cart']}, 21:43:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'talos_options': ['--disable-e10s'], 21:43:31 INFO - 'tests': ['tp5o']}, 21:43:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'tests': ['tp5o']}, 21:43:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'talos_options': ['--disable-e10s', 21:43:31 INFO - '--xperf_path', 21:43:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:43:31 INFO - 'tests': ['tp5n']}, 21:43:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:43:31 INFO - 'talos_options': ['--xperf_path', 21:43:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:43:31 INFO - 'tests': ['tp5n']}}, 21:43:31 INFO - 'talos.zip': {'path': '', 21:43:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:43:31 INFO - Running post-action listener: _resource_record_post_action 21:43:31 INFO - [mozharness: 2016-08-26 04:43:31.821368Z] Finished populate-webroot step (success) 21:43:31 INFO - [mozharness: 2016-08-26 04:43:31.821458Z] Running create-virtualenv step. 21:43:31 INFO - Running pre-action listener: _resource_record_pre_action 21:43:31 INFO - Running main action method: create_virtualenv 21:43:31 INFO - Creating virtualenv /builds/slave/test/build/venv 21:43:31 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 21:43:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:43:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:43:31 INFO - Using real prefix '/usr' 21:43:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:43:32 INFO - Installing distribute.............................................................................................................................................................................................done. 21:43:34 INFO - Installing pip.................done. 21:43:34 INFO - Return code: 0 21:43:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:43: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')]} 21:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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')]} 21:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43: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', 'pip>=1.5'] in /builds/slave/test/build 21:43: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 pip>=1.5 21:43:34 INFO - Using env: {'DISPLAY': ':0', 21:43:34 INFO - 'HOME': '/home/cltbld', 21:43:34 INFO - 'LANG': 'en_US.UTF-8', 21:43:34 INFO - 'LANGUAGE': 'en_US:en', 21:43:34 INFO - 'LOGNAME': 'cltbld', 21:43:34 INFO - 'MAIL': '/var/mail/cltbld', 21:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:34 INFO - 'MOZ_NO_REMOTE': '1', 21:43:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:34 INFO - 'NO_EM_RESTART': '1', 21:43:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:34 INFO - 'PWD': '/builds/slave/test', 21:43:34 INFO - 'SHELL': '/bin/bash', 21:43:34 INFO - 'SHLVL': '1', 21:43:34 INFO - 'TERM': 'linux', 21:43:34 INFO - 'TMOUT': '86400', 21:43:34 INFO - 'USER': 'cltbld', 21:43:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:34 INFO - '_': '/tools/buildbot/bin/python'} 21:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:34 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 21:43:34 INFO - Cleaning up... 21:43:34 INFO - Return code: 0 21:43:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:43: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')]} 21:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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')]} 21:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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', '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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:43: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 psutil>=3.1.1 21:43:34 INFO - Using env: {'DISPLAY': ':0', 21:43:34 INFO - 'HOME': '/home/cltbld', 21:43:34 INFO - 'LANG': 'en_US.UTF-8', 21:43:34 INFO - 'LANGUAGE': 'en_US:en', 21:43:34 INFO - 'LOGNAME': 'cltbld', 21:43:34 INFO - 'MAIL': '/var/mail/cltbld', 21:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:34 INFO - 'MOZ_NO_REMOTE': '1', 21:43:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:34 INFO - 'NO_EM_RESTART': '1', 21:43:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:34 INFO - 'PWD': '/builds/slave/test', 21:43:34 INFO - 'SHELL': '/bin/bash', 21:43:34 INFO - 'SHLVL': '1', 21:43:34 INFO - 'TERM': 'linux', 21:43:34 INFO - 'TMOUT': '86400', 21:43:34 INFO - 'USER': 'cltbld', 21:43:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:34 INFO - '_': '/tools/buildbot/bin/python'} 21:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:34 INFO - Downloading/unpacking psutil>=3.1.1 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:43:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:43:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:43:36 INFO - Installing collected packages: psutil 21:43:36 INFO - Running setup.py install for psutil 21:43:36 INFO - building 'psutil._psutil_linux' extension 21:43:36 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 21:43:37 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 21:43:37 INFO - building 'psutil._psutil_posix' extension 21:43:37 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 21:43:37 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 21:43:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:43:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:43:37 INFO - Successfully installed psutil 21:43:37 INFO - Cleaning up... 21:43:37 INFO - Return code: 0 21:43:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:43: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')]} 21:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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')]} 21:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:43: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 mozsystemmonitor==0.3 21:43:37 INFO - Using env: {'DISPLAY': ':0', 21:43:37 INFO - 'HOME': '/home/cltbld', 21:43:37 INFO - 'LANG': 'en_US.UTF-8', 21:43:37 INFO - 'LANGUAGE': 'en_US:en', 21:43:37 INFO - 'LOGNAME': 'cltbld', 21:43:37 INFO - 'MAIL': '/var/mail/cltbld', 21:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:37 INFO - 'MOZ_NO_REMOTE': '1', 21:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:37 INFO - 'NO_EM_RESTART': '1', 21:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:37 INFO - 'PWD': '/builds/slave/test', 21:43:37 INFO - 'SHELL': '/bin/bash', 21:43:37 INFO - 'SHLVL': '1', 21:43:37 INFO - 'TERM': 'linux', 21:43:37 INFO - 'TMOUT': '86400', 21:43:37 INFO - 'USER': 'cltbld', 21:43:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:37 INFO - '_': '/tools/buildbot/bin/python'} 21:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:43:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:43:39 INFO - Installing collected packages: mozsystemmonitor 21:43:39 INFO - Running setup.py install for mozsystemmonitor 21:43:39 INFO - Successfully installed mozsystemmonitor 21:43:39 INFO - Cleaning up... 21:43:39 INFO - Return code: 0 21:43:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:43: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')]} 21:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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')]} 21:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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', '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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:43: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 jsonschema==2.5.1 21:43:39 INFO - Using env: {'DISPLAY': ':0', 21:43:39 INFO - 'HOME': '/home/cltbld', 21:43:39 INFO - 'LANG': 'en_US.UTF-8', 21:43:39 INFO - 'LANGUAGE': 'en_US:en', 21:43:39 INFO - 'LOGNAME': 'cltbld', 21:43:39 INFO - 'MAIL': '/var/mail/cltbld', 21:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:39 INFO - 'MOZ_NO_REMOTE': '1', 21:43:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:39 INFO - 'NO_EM_RESTART': '1', 21:43:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:39 INFO - 'PWD': '/builds/slave/test', 21:43:39 INFO - 'SHELL': '/bin/bash', 21:43:39 INFO - 'SHLVL': '1', 21:43:39 INFO - 'TERM': 'linux', 21:43:39 INFO - 'TMOUT': '86400', 21:43:39 INFO - 'USER': 'cltbld', 21:43:39 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:39 INFO - '_': '/tools/buildbot/bin/python'} 21:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:39 INFO - Downloading/unpacking jsonschema==2.5.1 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:43:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:41 INFO - Downloading functools32-3.2.3-2.tar.gz 21:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:43:42 INFO - warning: no files found matching '*.txt' 21:43:42 INFO - no previously-included directories found matching 'build' 21:43:42 INFO - no previously-included directories found matching 'dist' 21:43:42 INFO - no previously-included directories found matching '.git*' 21:43:42 INFO - Installing collected packages: jsonschema, functools32 21:43:42 INFO - Running setup.py install for functools32 21:43:42 INFO - warning: no files found matching '*.txt' 21:43:42 INFO - no previously-included directories found matching 'build' 21:43:42 INFO - no previously-included directories found matching 'dist' 21:43:42 INFO - no previously-included directories found matching '.git*' 21:43:42 INFO - Successfully installed jsonschema functools32 21:43:42 INFO - Cleaning up... 21:43:42 INFO - Return code: 0 21:43:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:43:42 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')]} 21:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:42 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')]} 21:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:42 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:42 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 21:43:42 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 21:43:42 INFO - Using env: {'DISPLAY': ':0', 21:43:42 INFO - 'HOME': '/home/cltbld', 21:43:42 INFO - 'LANG': 'en_US.UTF-8', 21:43:42 INFO - 'LANGUAGE': 'en_US:en', 21:43:42 INFO - 'LOGNAME': 'cltbld', 21:43:42 INFO - 'MAIL': '/var/mail/cltbld', 21:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:42 INFO - 'MOZ_NO_REMOTE': '1', 21:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:42 INFO - 'NO_EM_RESTART': '1', 21:43:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:42 INFO - 'PWD': '/builds/slave/test', 21:43:42 INFO - 'SHELL': '/bin/bash', 21:43:42 INFO - 'SHLVL': '1', 21:43:42 INFO - 'TERM': 'linux', 21:43:42 INFO - 'TMOUT': '86400', 21:43:42 INFO - 'USER': 'cltbld', 21:43:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:42 INFO - '_': '/tools/buildbot/bin/python'} 21:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:43:42 INFO - Cleaning up... 21:43:42 INFO - Return code: 0 21:43:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:43:42 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')]} 21:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:42 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')]} 21:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:42 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:42 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 21:43:42 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 21:43:42 INFO - Using env: {'DISPLAY': ':0', 21:43:42 INFO - 'HOME': '/home/cltbld', 21:43:42 INFO - 'LANG': 'en_US.UTF-8', 21:43:42 INFO - 'LANGUAGE': 'en_US:en', 21:43:42 INFO - 'LOGNAME': 'cltbld', 21:43:42 INFO - 'MAIL': '/var/mail/cltbld', 21:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:42 INFO - 'MOZ_NO_REMOTE': '1', 21:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:42 INFO - 'NO_EM_RESTART': '1', 21:43:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:42 INFO - 'PWD': '/builds/slave/test', 21:43:42 INFO - 'SHELL': '/bin/bash', 21:43:42 INFO - 'SHLVL': '1', 21:43:42 INFO - 'TERM': 'linux', 21:43:42 INFO - 'TMOUT': '86400', 21:43:42 INFO - 'USER': 'cltbld', 21:43:42 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:42 INFO - '_': '/tools/buildbot/bin/python'} 21:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:42 INFO - Downloading/unpacking blobuploader==1.2.4 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43: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 21:43:44 INFO - Downloading blobuploader-1.2.4.tar.gz 21:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:43:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:43:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:43:45 INFO - Downloading docopt-0.6.1.tar.gz 21:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:43:45 INFO - Installing collected packages: blobuploader, requests, docopt 21:43:45 INFO - Running setup.py install for blobuploader 21:43:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:43:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:43:45 INFO - Running setup.py install for requests 21:43:45 INFO - Running setup.py install for docopt 21:43:45 INFO - Successfully installed blobuploader requests docopt 21:43:45 INFO - Cleaning up... 21:43:45 INFO - Return code: 0 21:43:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:43:45 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')]} 21:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:45 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')]} 21:43:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:45 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:45 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 21:43:45 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 21:43:45 INFO - Using env: {'DISPLAY': ':0', 21:43:45 INFO - 'HOME': '/home/cltbld', 21:43:45 INFO - 'LANG': 'en_US.UTF-8', 21:43:45 INFO - 'LANGUAGE': 'en_US:en', 21:43:45 INFO - 'LOGNAME': 'cltbld', 21:43:45 INFO - 'MAIL': '/var/mail/cltbld', 21:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:45 INFO - 'MOZ_NO_REMOTE': '1', 21:43:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:45 INFO - 'NO_EM_RESTART': '1', 21:43:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:45 INFO - 'PWD': '/builds/slave/test', 21:43:45 INFO - 'SHELL': '/bin/bash', 21:43:45 INFO - 'SHLVL': '1', 21:43:45 INFO - 'TERM': 'linux', 21:43:45 INFO - 'TMOUT': '86400', 21:43:45 INFO - 'USER': 'cltbld', 21:43:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:45 INFO - '_': '/tools/buildbot/bin/python'} 21:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:43:46 INFO - Running setup.py (path:/tmp/pip-fSHOff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:43:46 INFO - Running setup.py (path:/tmp/pip-vQODUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:43:46 INFO - Running setup.py (path:/tmp/pip-ehICm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:43:46 INFO - Running setup.py (path:/tmp/pip-rb8z9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:43:46 INFO - Running setup.py (path:/tmp/pip-h6Zu82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:43:46 INFO - Running setup.py (path:/tmp/pip-d9vbPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:43:46 INFO - Running setup.py (path:/tmp/pip-jNtRZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:43:46 INFO - Running setup.py (path:/tmp/pip-9mYZMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:43:46 INFO - Running setup.py (path:/tmp/pip-qg9B4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:43:46 INFO - Running setup.py (path:/tmp/pip-pieQGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:43:46 INFO - Running setup.py (path:/tmp/pip-oEFOE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:43:46 INFO - Running setup.py (path:/tmp/pip-iMCtFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:43:47 INFO - Running setup.py (path:/tmp/pip-_47og7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:43:47 INFO - Running setup.py (path:/tmp/pip-pPwIrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:43:47 INFO - Running setup.py (path:/tmp/pip-reY7xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:43:47 INFO - Running setup.py (path:/tmp/pip-2V07ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:43:47 INFO - Running setup.py (path:/tmp/pip-Risjiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:43:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:43:47 INFO - Running setup.py install for manifestparser 21:43:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:43:47 INFO - Running setup.py install for mozcrash 21:43:47 INFO - Running setup.py install for mozdebug 21:43:47 INFO - Running setup.py install for mozdevice 21:43:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:43:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:43:47 INFO - Running setup.py install for mozfile 21:43:47 INFO - Running setup.py install for mozhttpd 21:43:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozinfo 21:43:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozInstall 21:43:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozleak 21:43:48 INFO - Running setup.py install for mozlog 21:43:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for moznetwork 21:43:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozprocess 21:43:48 INFO - Running setup.py install for mozprofile 21:43:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozrunner 21:43:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:43:48 INFO - Running setup.py install for mozscreenshot 21:43:48 INFO - Running setup.py install for moztest 21:43:49 INFO - Running setup.py install for mozversion 21:43:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:43:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:43:49 INFO - Cleaning up... 21:43:49 INFO - Return code: 0 21:43:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:49 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:49 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 21:43:49 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 21:43:49 INFO - Using env: {'DISPLAY': ':0', 21:43:49 INFO - 'HOME': '/home/cltbld', 21:43:49 INFO - 'LANG': 'en_US.UTF-8', 21:43:49 INFO - 'LANGUAGE': 'en_US:en', 21:43:49 INFO - 'LOGNAME': 'cltbld', 21:43:49 INFO - 'MAIL': '/var/mail/cltbld', 21:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:49 INFO - 'MOZ_NO_REMOTE': '1', 21:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:49 INFO - 'NO_EM_RESTART': '1', 21:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:49 INFO - 'PWD': '/builds/slave/test', 21:43:49 INFO - 'SHELL': '/bin/bash', 21:43:49 INFO - 'SHLVL': '1', 21:43:49 INFO - 'TERM': 'linux', 21:43:49 INFO - 'TMOUT': '86400', 21:43:49 INFO - 'USER': 'cltbld', 21:43:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:49 INFO - '_': '/tools/buildbot/bin/python'} 21:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:43:49 INFO - Running setup.py (path:/tmp/pip-WaNGHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:43:49 INFO - Running setup.py (path:/tmp/pip-JkQzWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:43:49 INFO - Running setup.py (path:/tmp/pip-Y4ZU5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:43:49 INFO - Running setup.py (path:/tmp/pip-u8Kboh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:43:49 INFO - Running setup.py (path:/tmp/pip-Z5Aj82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:43:49 INFO - Running setup.py (path:/tmp/pip-_DMVYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:43:49 INFO - Running setup.py (path:/tmp/pip-XRJMxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:43:49 INFO - Running setup.py (path:/tmp/pip-bboFGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:43:49 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)) 21:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:43:49 INFO - Running setup.py (path:/tmp/pip-IBUiFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:43:50 INFO - Running setup.py (path:/tmp/pip-qjHbHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:43:50 INFO - Running setup.py (path:/tmp/pip-NnHtCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:43:50 INFO - Running setup.py (path:/tmp/pip-F4nKi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:43:50 INFO - Running setup.py (path:/tmp/pip-kRFsI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:43:50 INFO - Running setup.py (path:/tmp/pip-tyx4EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:43:50 INFO - Running setup.py (path:/tmp/pip-1wAI5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:43:50 INFO - Running setup.py (path:/tmp/pip-mhZsEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:43:50 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)) 21:43:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:43:50 INFO - Running setup.py (path:/tmp/pip-h5IZNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:43:50 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)) 21:43:50 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)) 21:43:50 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)) 21:43:50 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)) 21:43:50 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)) 21:43:50 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)) 21:43:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:43:50 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 21:43:50 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 21:43:50 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 21:43:50 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 21:43:50 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 21:43:50 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 21:43:52 INFO - Downloading blessings-1.6.tar.gz 21:43:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:43:52 INFO - Installing collected packages: blessings 21:43:52 INFO - Running setup.py install for blessings 21:43:52 INFO - Successfully installed blessings 21:43:52 INFO - Cleaning up... 21:43:52 INFO - Return code: 0 21:43:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:43:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:43:52 INFO - Reading from file tmpfile_stdout 21:43:52 INFO - Current package versions: 21:43:52 INFO - argparse == 1.2.1 21:43:52 INFO - blessings == 1.6 21:43:52 INFO - blobuploader == 1.2.4 21:43:52 INFO - docopt == 0.6.1 21:43:52 INFO - functools32 == 3.2.3-2 21:43:52 INFO - jsonschema == 2.5.1 21:43:52 INFO - manifestparser == 1.1 21:43:52 INFO - mozInstall == 1.12 21:43:52 INFO - mozcrash == 0.17 21:43:52 INFO - mozdebug == 0.1 21:43:52 INFO - mozdevice == 0.48 21:43:52 INFO - mozfile == 1.2 21:43:52 INFO - mozhttpd == 0.7 21:43:52 INFO - mozinfo == 0.9 21:43:52 INFO - mozleak == 0.1 21:43:52 INFO - mozlog == 3.3 21:43:52 INFO - moznetwork == 0.27 21:43:52 INFO - mozprocess == 0.23 21:43:52 INFO - mozprofile == 0.28 21:43:52 INFO - mozrunner == 6.12 21:43:52 INFO - mozscreenshot == 0.1 21:43:52 INFO - mozsystemmonitor == 0.3 21:43:52 INFO - moztest == 0.7 21:43:52 INFO - mozversion == 1.4 21:43:52 INFO - psutil == 3.1.1 21:43:52 INFO - requests == 1.2.3 21:43:52 INFO - wsgiref == 0.1.2 21:43:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:43:52 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')]} 21:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:52 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')]} 21:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:52 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:52 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 21:43:52 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 21:43:52 INFO - Using env: {'DISPLAY': ':0', 21:43:52 INFO - 'HOME': '/home/cltbld', 21:43:52 INFO - 'LANG': 'en_US.UTF-8', 21:43:52 INFO - 'LANGUAGE': 'en_US:en', 21:43:52 INFO - 'LOGNAME': 'cltbld', 21:43:52 INFO - 'MAIL': '/var/mail/cltbld', 21:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:52 INFO - 'MOZ_NO_REMOTE': '1', 21:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:52 INFO - 'NO_EM_RESTART': '1', 21:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:52 INFO - 'PWD': '/builds/slave/test', 21:43:52 INFO - 'SHELL': '/bin/bash', 21:43:52 INFO - 'SHLVL': '1', 21:43:52 INFO - 'TERM': 'linux', 21:43:52 INFO - 'TMOUT': '86400', 21:43:52 INFO - 'USER': 'cltbld', 21:43:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:52 INFO - '_': '/tools/buildbot/bin/python'} 21:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 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)) 21:43:53 INFO - Cleaning up... 21:43:53 INFO - Return code: 0 21:43:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:43: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')]} 21:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43: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')]} 21:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:43:53 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 0x1c63030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f24180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, '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': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', '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 21:43:53 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 21:43:53 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 21:43:53 INFO - Using env: {'DISPLAY': ':0', 21:43:53 INFO - 'HOME': '/home/cltbld', 21:43:53 INFO - 'LANG': 'en_US.UTF-8', 21:43:53 INFO - 'LANGUAGE': 'en_US:en', 21:43:53 INFO - 'LOGNAME': 'cltbld', 21:43:53 INFO - 'MAIL': '/var/mail/cltbld', 21:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:43:53 INFO - 'MOZ_NO_REMOTE': '1', 21:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:43:53 INFO - 'NO_EM_RESTART': '1', 21:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:43:53 INFO - 'PWD': '/builds/slave/test', 21:43:53 INFO - 'SHELL': '/bin/bash', 21:43:53 INFO - 'SHLVL': '1', 21:43:53 INFO - 'TERM': 'linux', 21:43:53 INFO - 'TMOUT': '86400', 21:43:53 INFO - 'USER': 'cltbld', 21:43:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:43:53 INFO - '_': '/tools/buildbot/bin/python'} 21:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:43:53 INFO - Cleaning up... 21:43:53 INFO - Return code: 0 21:43:53 INFO - Running post-action listener: _resource_record_post_action 21:43:53 INFO - Running post-action listener: _start_resource_monitoring 21:43:53 INFO - Starting resource monitoring. 21:43:53 INFO - [mozharness: 2016-08-26 04:43:53.417629Z] Finished create-virtualenv step (success) 21:43:53 INFO - [mozharness: 2016-08-26 04:43:53.418102Z] Running install step. 21:43:53 INFO - Running pre-action listener: _resource_record_pre_action 21:43:53 INFO - Running main action method: install 21:43:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:43:53 INFO - Reading from file tmpfile_stdout 21:43:53 INFO - Detecting whether we're running mozinstall >=1.0... 21:43:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:43:53 INFO - Reading from file tmpfile_stdout 21:43:53 INFO - Output received: 21:43:53 INFO - Usage: mozinstall [options] installer 21:43:53 INFO - Options: 21:43:53 INFO - -h, --help show this help message and exit 21:43:53 INFO - -d DEST, --destination=DEST 21:43:53 INFO - Directory to install application into. [default: 21:43:53 INFO - "/builds/slave/test"] 21:43:53 INFO - --app=APP Application being installed. [default: firefox] 21:43:53 INFO - mkdir: /builds/slave/test/build/application 21:43:53 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'] 21:43:53 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 21:44:10 INFO - Reading from file tmpfile_stdout 21:44:10 INFO - Output received: 21:44:10 INFO - /builds/slave/test/build/application/firefox/firefox 21:44:10 INFO - Running post-action listener: _resource_record_post_action 21:44:10 INFO - [mozharness: 2016-08-26 04:44:10.060946Z] Finished install step (success) 21:44:10 INFO - [mozharness: 2016-08-26 04:44:10.061232Z] Running run-tests step. 21:44:10 INFO - Running pre-action listener: _resource_record_pre_action 21:44:10 INFO - Running main action method: run_tests 21:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:44:10 INFO - Python 2.7.3 21:44:10 INFO - Return code: 0 21:44:10 INFO - grabbing minidump binary from tooltool 21:44:10 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')]} 21:44:10 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 0x1f223b0>, '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 0x1f232b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f23740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:44:10 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 21:44:10 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 21:44:10 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 21:44:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:44:10 INFO - Return code: 0 21:44:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:44:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:44:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:44:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:44:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:44:10 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/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:44:10 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/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 21:44:10 INFO - Using env: {'DISPLAY': ':0', 21:44:10 INFO - 'HOME': '/home/cltbld', 21:44:10 INFO - 'LANG': 'en_US.UTF-8', 21:44:10 INFO - 'LANGUAGE': 'en_US:en', 21:44:10 INFO - 'LOGNAME': 'cltbld', 21:44:10 INFO - 'MAIL': '/var/mail/cltbld', 21:44:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:44:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:10 INFO - 'MOZ_NO_REMOTE': '1', 21:44:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:10 INFO - 'NO_EM_RESTART': '1', 21:44:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:10 INFO - 'PWD': '/builds/slave/test', 21:44:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:44:10 INFO - 'SHELL': '/bin/bash', 21:44:10 INFO - 'SHLVL': '1', 21:44:10 INFO - 'TERM': 'linux', 21:44:10 INFO - 'TMOUT': '86400', 21:44:10 INFO - 'USER': 'cltbld', 21:44:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001', 21:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:10 INFO - '_': '/tools/buildbot/bin/python'} 21:44:10 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/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:44:10 INFO - mozversion application_buildid: 20160825171918 21:44:10 INFO - mozversion application_changeset: 458d5f0a55bbb2423091a2108cab633321a64d26 21:44:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:44:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:44:10 INFO - mozversion application_name: Firefox 21:44:10 INFO - mozversion application_remotingname: firefox-dev 21:44:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:44:10 INFO - mozversion application_vendor: Mozilla 21:44:10 INFO - mozversion application_version: 50.0a2 21:44:10 INFO - mozversion platform_buildid: 20160825171918 21:44:10 INFO - mozversion platform_changeset: 458d5f0a55bbb2423091a2108cab633321a64d26 21:44:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:44:10 INFO - mozversion platform_version: 50.0a2 21:44:10 INFO - using testdate: 1472186650 21:44:10 INFO - actual date: 1472186650 21:44:10 INFO - starting webserver on 'localhost:37377' 21:44:10 INFO - SUITE-START | Running 2 tests 21:44:10 INFO - TEST-START | dromaeo_css 21:44:10 INFO - operating with platform_type : linux_ 21:44:10 INFO - Initialising browser for dromaeo_css test... 21:44:10 INFO - TEST-INFO | started process 6372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJqoZc0/profile http://localhost:37377/getInfo.html) 21:44:15 INFO - PROCESS | 6372 | __metrics Screen width/height:1600/1200 21:44:15 INFO - PROCESS | 6372 | colorDepth:24 21:44:15 INFO - PROCESS | 6372 | Browser inner width/height: 1024/697 21:44:15 INFO - PROCESS | 6372 | __metrics 21:44:16 INFO - PROCESS | 6372 | 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" 21:44:16 INFO - PROCESS | 6372 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:44:17 INFO - TEST-INFO | 6372: exit 0 21:44:17 INFO - Browser initialized. 21:44:17 INFO - Running cycle 1/1 for dromaeo_css test... 21:44:17 INFO - TEST-INFO | started process 6457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJqoZc0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:44:29 INFO - PROCESS | 6457 | RSS: Main: 145264640 21:44:29 INFO - PROCESS | 6457 | 21:45:19 INFO - PROCESS | 6457 | 1472186719730 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] 21:47:28 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37377/tests/dromaeo/cssquery-ext.html) 21:47:30 INFO - PROCESS | 6457 | RSS: Main: 225099776 21:47:30 INFO - PROCESS | 6457 | 21:50:32 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-ext.html (next: http://localhost:37377/tests/dromaeo/cssquery-jquery.html) 21:50:32 INFO - PROCESS | 6457 | RSS: Main: 232992768 21:50:32 INFO - PROCESS | 6457 | 21:53:34 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37377/tests/dromaeo/cssquery-mootools.html) 21:53:35 INFO - PROCESS | 6457 | RSS: Main: 204476416 21:53:35 INFO - PROCESS | 6457 | 21:56:37 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37377/tests/dromaeo/cssquery-prototype.html) 21:56:37 INFO - PROCESS | 6457 | RSS: Main: 176820224 21:56:37 INFO - PROCESS | 6457 | 21:59:40 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37377/tests/dromaeo/cssquery-yui.html) 21:59:41 INFO - PROCESS | 6457 | RSS: Main: 177033216 21:59:41 INFO - PROCESS | 6457 | 22:02:43 INFO - PROCESS | 6457 | Cycle 1(1): loaded http://localhost:37377/tests/dromaeo/cssquery-yui.html (next: http://localhost:37377/tests/dromaeo/cssquery-dojo.html) 22:02:44 INFO - PROCESS | 6457 | RSS: Main: 252850176 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | __start_tp_report 22:02:44 INFO - PROCESS | 6457 | _x_x_mozilla_page_load 22:02:44 INFO - PROCESS | 6457 | _x_x_mozilla_page_load_details 22:02:44 INFO - PROCESS | 6457 | |i|pagename|runs| 22:02:44 INFO - PROCESS | 6457 | |0;dojo.html;3230.2731885325798;3256.039468356835;3256.7394608431314;3285.080177550287;3309.4043072247;5526.143447765598;5618;5677.545796336293;5683.175939488767;5685.232493613361;5642;5721.056025755745;5724.542036637069;5724.7996320128805;5767.1637612546265;4976.203807390818;5136.02415541047;5176.456472070435;5218.139007063849;5256.26412302278;5015.824446144386;5083.18669012958;5122.385313762354;5129.051125541787;5130.820421285256;5049.318342023835;5098.719570423644;5125.2312153176945;5130.9230361544605;5155.510226528474;5188.247704082924;5197.038547868657;5199.376074871021;5206.895862082775;5232.529072383504;4971.651984361096;5052.343195384613;5066.417362003343;5109.846704598868;5116.488351164854;5551.528120109796;5570.805021824257;5586.385497595261;5615.326160860723;5624.21261023457;6105.948836118943;6116.541259405518;6117.8112229642;6122.693865306762;6136.69316534176;5980.551458640576;6025.638461692269;6036.15493830864;6101.90753056428;6136.110264011693;5658.886822263532;5703.914441283384;5712.371639119693;5716.742746576414;5716.8856622867725;5965.701714914237;6205.410486003822;6207.286162091417;6214.471769899519;6216.658083805343;5818.44724751148;5834.737436815353;5847.415258474119;5952.76188952437;5961.867245223394;6020.608660437145;6020.849478762978;6023.608465449732;6045.697715114226;6055.182550355646;5747.597668163272;5962.34414214436;5977.970110149421;5980;5986.191864098378;5913.231279933581;5944.821655350346;5949.25634295713;5995.430434108753;6049;4620.768961551909;4624.514425985223;4624.65315101362;4626.028534007829;4655.767211639404;4025.2150830587752;4126.051008268115;4137.441445404832;4145.440365550672;4147.605977432139;5649.096144616841;5662.8301150965535;5669.943300566942;5728.255326807488;5763.0202865512965;5894.233749612523;5935.910961335583;5939.198208241918;5980.790672326448;6001.399860014051;5633.774649014075;5727.77088916447;5740.799072032459;5749.1663708762835;5750.971245143747;5090.363704536933;5101.285819985205;5136.332276803992;5173.663711858792;5214.139666954986;6401.807945761635;6411.166548348685;6412.935870641234;6414.679266036773;6418.84460797061;5887.911681324869;6156.78451254204;6174.413430724074;6176.81469555914;6178.536609754196;6115.1794953435965;6166.907496387468;6178.073289006685;6223.471004964447;6242.406971337716;5916.763329466945;5974.402559744165;5981.282246130492;5982.820515384546;6019.548533860066;5813.883722325614;6073.180120683828;6079.361667024898;6080.513558915364;6095.207623008981;5894.381089985662;5901.026330655565;5942.227510423617;5997.970010150096;6028.849278768066;5909.23179986599;5946.989011867906;5960;5969.850753731192;5971.940280597312;5692.31692196939;5836.737346819318;5874.5887787856145;5885;5894.204282421818;5907.438793314628;5958.374370691015;5961.791337303173;6043.969780151071;6078.939210607838;5870.031444811559;5873.236479257669;5877.4122587740885;5877.706114694162;5880.206172166874 22:02:44 INFO - PROCESS | 6457 | |1;ext.html;623.9937600623992;633.0125004992212;655.171208421347;673.7843889955217;676.3067855448166;35140.64859351406;46017.76991115044;46487.767561162145;46583.06833863321;46727.53272467275;48444.75777621107;48824;48891.51108488923;48905.7554712226;49037.75481122598;8457.323414126886;9187.402818816772;9361.110694484014;9368.110029547182;9377.202937750266;8579.227869491744;8657.350698697592;8671.783205419884;10897.400642964674;11209;11391.829122563167;11426.771464570746;11430.657080287563;11461.255018423817;11463.541458341699;11176.944115279453;11214.66356009321;11245.662630121109;11250.606228781953;11293.661190164308;11023.503942322615;11086.61196858106;11090.279131856403;11101.44492775358;11101.44492775358;45965;46246;46293.76853115747;46333.30500042502;46350;3774.6036666149935;3822.961770382316;3858.266929283427;3924.2347742190286;4147.274227010261;34147;34436.139096522784;34624.13439664003;34697.82651086753;34828.82585587081;4674.719516828967;4722.834700785456;4742.241241401359;4748.19280722278;4829.323894654751;393224;393650;394042;394584;394938;64364.678176609756;64596.35403645904;64697;64775.67612161909;64805.35194647993;40162.79918600388;40739;40817;43296.3505547417;66610.3338966604;16811.57971050709;16921.561667258175;16946.57633559146;16957.915210423867;16969.321227151024;11601.07191424684;12629.052821038458;12688.74622507563;12729.68175795594;12730.17253878495;20294.594108117755;20869.68695469569;21005.789942100688;21838.14375778045;21978.670319945213;7590.354819840265;7762.91790291878;7848.411369147337;7857.646405911748;7919.960400197962;1078.962236321725;1089.8201796703456;1091.6670415523183;1105.4859490337033;1110.245033377295;34684.653153468644;35074.64925350714;35641;35685.107872302884;35989.1002724934;1120.3781901045;1176.264834478451;1176.2824676947055;1221.035382048172;1254.7804134276464;18461.81538184601;18794.154263058193;18931.810681893006;18949.526261843563;18973.33593324227;655738;660477;661568;662815;663567;41738.373924391155;41851.58148418477;41903;41928;42018.369724454154;1292.65744577687;1405.8242719660043;1418.0215651200638;1431.2199851081186;1450.245872146499;34190;36322.636773632985;36525.63474365223;36673;37028.259434810614;54239;54561.45438545564;54651.45348546463;54653.72673136609;54667.179992300946;416670;417825;417883;418320;518636;46258.53741462677;46700.53299466962;47186.29220561763;47489;47573.52426475829;52752;53078.469215307356;53135.4686453146;53432.465675344305;54323.72838135784;43632.02268148897;45145.09709805851;45583;46422.53577464317;47762.76118619385;44608.55391446044;44847;46559.76720116378;49565.75217123891;49596.50403495919;45469.77265113653;46493.53506464892;46875.29687054765;46905.76547117379;47052;38552.80723596364;40985.59014409937;41251;41297;41416.79291603523;30738.538921916635;30874.53688194724;30903.536446953764;31025;31238 22:02:44 INFO - PROCESS | 6457 | |2;jquery.html;389.2740039825726;409.61291579457423;425.72753437799804;432.22415763704146;433.7072476078646;15337.309821058052;20400.489987750312;20714.171433142656;20793.272235471763;20796.688049679247;19614.117364718586;19699.803001969976;19725.112369943352;19737;19949.900250498762;5830.475257226849;5918.911216331759;5968.7612495500125;5998.460138587527;6012.398760123996;5911.172435858962;5954.553408494358;5977.5815692901515;5979.820605381824;5984.790532331369;5553.72231388431;5769.16347129666;5811.535077193836;5888.116782482619;5888.793892213774;5870;6045.365236650155;6402.487800975934;6456.0961465394885;6580.638064906429;5813.82558523245;5819.621724587931;5987.550933679991;6002.159697642333;6003.249593800775;14399.280035998157;14703.411863525445;14765.852341476662;14772.852271477363;21571.784282157136;2446.6330050492393;2604.9479010419873;2614.2157352794275;2680.3433158876005;3077.753779697619;10529.947350263199;10715.83926241107;10819.891801081967;10863.83704244437;10864;1962.5584243545088;1979.7822239553639;2019.0611365715013;2086.9791302086937;2135.9359219223447;86870;89637;90572;91356;92772;11291.266067705574;11389.259698119597;11601.361925094032;11692.766144677058;11703.414829258503;16191.352345906032;16403.507894763177;16432.342706291853;16451.42420015318;16462.917685411736;7649.579273140031;7742.922570774333;7767.689292428239;7836.764897053097;7929.44493885434;7583.886241706379;7600.239976002355;7706.691732330644;8025.438219324708;8040.4773689711155;4931;4954.826581069645;4998.650094493423;5028.446870844205;5177.068127737043;2070.254708305009;2102.6845973104155;2111.6832475128545;2120.7985241402043;2182.2362173239558;798.6941350891218;1016.1768967136643;1029.8352263637933;1056.0390045059114;1127.7575321305958;19972.300969466;20086.39740807778;20428.18287268522;20451.28420505275;20520.07659655319;1033.7260625934134;1054;1083.7399024234285;1094.6332978452149;1098.5441041967654;11056.944715276533;11446.656600302003;11478.426078696031;11585.073194144448;11683.415829208505;504948;583398;596903;638819;647445;96583;99102.5044874771;100010;100936;105527;994.6518718448485;1044.8589440425596;1051.8317069269033;1054.0408228512017;1055.0188324857957;17227.22477488491;17427.912860435616;17632.647347053244;17917.37289194872;17928.01395923237;44194.77902610466;44224.33663494986;45840.54159458363;46458.07083858371;46598.30102548532;64100;65169.67415162894;65516.672416637615;65809;67431;8660.220580147818;8713.651453941771;8879.289656827568;8885.822283554422;8968.013518512827;13189.142705724098;13404.396802144121;13546.254955977516;14170.645733856323;14190;10881.960511915962;11506.712332191762;11544.960953514224;11988.400579971141;12079.879201208227;11845.23006004607;11886.524539018343;12041.157119001586;12169.391530423267;12430.316332601793;12048.337341446304;12095.818562721743;12134.211276267015;12373.443195056425;12579.371031448574;7724.227577242231;7972.720954766555;8550.74347769568;8573.485590864568;8968.775780605538;11788.115891308014;12152;12166.783321667763;12194.146409751233;12211.694707632025 22:02:44 INFO - PROCESS | 6457 | |3;mootools.html;1499.5126583860244;1584.8335924727903;1745.9301627934883;1855.8728542406038;1916.4633902507294;8422.494650320985;9134.954325228373;9156.58795354209;9877.456739879295;9878.357906736057;9208.217301529377;9536.141747242747;9562.521873906311;9577.425354478737;9599.808003839937;2415.5168966206743;2434.500927309897;2437.5003124359464;2442.3283597010877;2445.40087678519;2142.828573714099;2304.9078036878504;2378.369732021016;2421.3462365161395;2436.6954130733657;2428.781409673129;2457.066314800373;2459.692538432696;2463.6674049003336;2465.0263146057296;2362.137819695807;2377.072941552796;2379.155399833069;2386.78518933296;2388.2715771689627;2387.582173119697;2388.1641425500934;2407.9398015049587;2417.9395515112265;2422.5881600127846;9638.180754635865;9672.661456849048;9732.659356922544;9772.462514561696;9777.364471309342;5576.414476479952;5686.2039314496005;5733.885322293572;5741.454561816662;5741.88516229673;3357.5467311912826;3435.12404336895;3453;3463.428534291839;3487.2851065531504;1106.3748981825242;1184.6919800851754;1206.215959626241;1238.0405185980846;1254.943527541263;13607.59177224675;13613.727725445435;13614;13617.931910340385;13629.386677599532;4835.975820120947;5065.214891691794;5072.82245121419;5124.923126153036;5146.639735218499;11480.655580332603;11486.712832179095;11486.770264594661;11487.597934072268;11490.885091149148;5741.770329186786;5755.309362876482;5759.308882934075;5763.971180144073;5768.74040668171;5092.083424983539;5193.298904647824;5269.341332333458;5277.23480095384;5330.813421530305;4072.6378700353725;4161.375793630979;4177.874663760092;4224.9155016899485;4329.653627709776;1113.365381732421;1216.0636310041218;1248.8626251112553;1254.4919307680404;1259.3199672177104;1003.5885287032281;1041.4011943132577;1046.4140081554353;1055.8521806947033;1061.310148403544;6090.908636370458;6231.283402408691;6373.8725225496155;6399.392057754506;6433.035044743232;1029.8609687692215;1045.2265323660436;1051.2063392138887;1062.0972173652488;1092.5192915117482;4332.458442694663;4460.442444694413;4484.080763443486;4538.364628951949;4578.0157266187925;11817.586384476503;12202.206856554472;12241.510339586495;12244.448999795031;12250.203736757083;11326;11748.88251117495;12187.329696866758;12188.878111218952;12193.96351310131;7985.5208687478935;8152.144024877302;8161.143079976636;8162.591870406574;8164.265216130577;6622.503312251697;6692.431143352869;6745.156855393076;6763.864722705617;6892.827679308058;10927.617533386294;11235.213535052468;11244.943775281072;11398.94300528492;11410.94294528522;11131.218120171625;11398.031167350868;11435.771284574428;11555.711107222387;11561.248518846249;8435.409521333448;8560.57197140153;8629.913700862911;8634.654613815377;8649.610767515604;9271.490068046322;9281.67514137002;9347.579358928728;9350.485723285285;9432.52837358143;6563.96718016426;8052;8101.473404228706;8186.058603260702;8233.17668233172;8297.95851020741;8322.292605128632;8341.457805242882;8341.874871876806;8569.10024447424;7847.176046515034;7888.053433587776;7988.041435027834;8092.635831387484;8094.3929205311015;4787.54518320759;4834.903301934012;4864.562189402971;4882.877928051685;4959.181735013683;5446.210299506629;5487.862803429786;5512.228288039598;5557.749901254372;5562.443755624567 22:02:44 INFO - PROCESS | 6457 | |4;prototype.html;336.8888266871933;347.63845600575405;347.7774224496323;357.5798436836718;358.93359728450224;5560.165975103731;5617.382087970325;5637.267155269814;5674.829755107343;5683.318001839774;5523.226748255248;5544.667319960805;5554.527865131468;5560.638558493695;5579.958922387298;3922.5489068757174;4159.313713237312;4345.327049086109;4379.737215767048;4389.056352884129;4350.97824510877;4364.672649551297;4373.365861950031;4388.934165987513;4388.956110438902;4339.652827773786;4363.694541382105;4374.540673229313;4375.737455752649;4394.780260986938;4174.624283814456;4235.957640423587;4380.474343078819;4384.167008268419;4402.405675233835;3857.5756666766633;3867.7236511950987;3872.7869967151796;3877.7867217303037;3915.197384536163;5409.377921539034;5421.376541697677;5446.645968012067;5459.617826752129;5470.5350045246205;4817.084753896784;4976.4525902150735;4990.276409920578;5016.724080175589;5037.345145131146;5832.854178645525;5836.153757705151;5842.941570584282;5862.355140934494;5880.9411905880825;4301.730989358147;4311.8059687313835;4317.611414972636;4324.65402767781;4331.350297455376;5723.742431590588;5745.540356771448;5876.794312199096;6059.454839016067;6090.147379366892;4164.9583504165175;4169.478815148106;4273.209456250604;4317.676174286941;4320.200762858881;4362.280223763044;4365.454318210223;4375.2780791169735;4378.4745830499905;4380.649548036149;4841.7653498357695;4907.1657818171;4969.950300497021;4971.105201063771;4980.651354405157;5848.771757930797;5849.181114643953;5853.326867410217;5889.646621202742;5892.322382925932;1481.9629509262354;1509.6527798606382;1512.4026009726317;1513.7577987521943;1513.9856296281298;923.5151545438698;954.346272803132;958.2860768727343;960.7357976556503;975;5049.318342023853;5072.492750724898;5095;5109.284700141983;5125.46289586657;5293.338332708411;5313.681179129265;5320.973395133;5364.249005139283;5364.8658783529945;5037.697738135724;5066.721330326867;5082.33929589151;5342.706151161646;5793.304803423532;3186.665400132999;3200.5519227308196;3209.7592680548587;3210.9839450802597;3212.245122396235;6132.816015519542;6273.215848018997;6281.403266689657;6285.811425657238;6318.905216421757;6077.665728384893;6079.240094988127;6086.695665216722;6096.786612468542;6136.447719705248;6210.161628180138;6233.2208473940755;6234.812955611248;6252.3122456530655;6348.301686814539;5790.507806836466;5983.935542237921;6018.548608854266;6034.426729460694;6121.173641558511;5922.407759224043;5931.822045338647;5934.258217722785;5947.375525569752;5950.404959504015;5809.36097029318;5990.88018239624;6023.879522409615;6192.195014648067;6261.373862613885;4284.957150428456;4297.205017071852;4304.3543468479975;4307.310830267143;4307.763073030888;6001.609895356963;6013.81958541227;6074.362191969956;6079.90243366038;6083.69581520931;5646.774129034792;5874.383189765013;5935;5944.167816505606;5975.940240597713;5921.822345329647;5922.496587790086;5924.170616113834;5930.762769489172;5937.150167919336;5641.379448260606;5847.415258474119;5850.327212370632;5860.326062502867;5885;5910.611006413404;5946.762129514944;5955.225820643289;5972.850678733066;5972.9701351494705;5777.591221179524;5803.12953057028;5814.94185058144;5817.331006934257;5823.679697616672 22:02:44 INFO - PROCESS | 6457 | |5;yui.html;847.9872801907973;887.840188766022;904.7150147703469;917.6237742525564;941.1341565759501;17226.82773172268;17840.91079544602;17854.553636159104;17858.553536161602;17874.731879021794;16503;16649.583760405967;16662.916685416585;16669.49991500257;16695.66608667826;737.4395459450816;757.3032809814983;757.8484303139367;758.3932853717032;869.2871845087031;880.7621942075635;887.4542156573725;891.4205766251956;891.7369376034086;891.754767438953;931.9161275485205;941.55276243784;942.5146049784366;942.5993952570165;943.4952300519224;936.5972631768337;936.9250459963221;937.2689302344182;937.3532262738688;938.3666025432806;558.430400990991;578.037567450195;579.6637949988997;691.6576294734139;696.9442444604471;14118.505852295222;14515.419383224658;14540.563783086523;15017.399304027826;15036.473723419627;16084.19579021056;16645.33418663264;16762.07808570528;17016.574585635335;17041.57396065096;12860.742785144339;12985.35073246334;13110.410031548603;13138.277394743382;13217.471301147943;4102.856400025985;4152.4601801765875;4187.91624167515;4197.958020419787;4199.559046300155;89900.55049724775;89990.55004724934;89996;90324;91852.54073729656;12745.044121690726;12864.099513034185;12915.22508649484;13052.282124483054;13073.869261307455;25394.238172854843;25439.10963116282;25706.742932570807;26093.347666308495;26238.47523049528;9089.506710390748;9104;9546.42721436702;9627.181689556326;9778.315517913677;5145.099607568661;5422.186671999153;5460.399356070829;5498.560115190855;5568.749406276727;3887.7084218683713;4014.879553613338;4015.638592526628;4021.8190181441905;4028.899277518027;658.980230593083;670.6345041952147;673.0745225315192;680.1158493957835;683.5283654278531;720.326494727421;723.6273319240595;774.1329710724025;797.5653268968426;812.5937031484258;9654.71035868925;10690.251682382159;10948.781024379627;10981.286216395909;10992;743.0117943135616;747.9626018699043;763.0508086246728;769.9422543309274;780.2236774409499;5138.563222126087;5325.547328477045;5349.277847490539;5354.41101478837;5402.324709411324;130438;135779.32110339386;137257;139011;144630;110639.44680276547;114916;116128;117471;118864.40567797105;77691;85412.57293713492;108328;110018;113544.43227783973;9600.519974001132;10015.098641122042;10100.797984040424;10138.138258248131;10141.594336226763;59544;60571.69714151401;61311.38688613057;62907;63375.36624633879;93999.53000235229;94118;94976.52511737397;96355.51822240844;96430.51784741032;13439.395227214995;13713.314334283046;13848.515301964786;13922.4431022762;14033.29833508341;14817;15045.699086018438;15069.397224111352;16098.195090245676;16356.100414477318;13198.07613467048;13300.866991329964;13823.723525529233;14128.152310861382;14332.140071595737;14124.576262712135;14132.374776900788;14339.35472903701;14373.712525749635;14603.92698036503;13222.933885330513;13387.933060334635;13478.123921945435;13500.054996150175;13549.932250338685;10619.30974486656;10891.509882055485;11038.779224415306;11131.944340278247;11475.426228688699;11099;11301.218134119217;11309.208355415041;11348.205625606459;11527 22:02:44 INFO - PROCESS | 6457 | __end_tp_report 22:02:44 INFO - PROCESS | 6457 | __start_cc_report 22:02:44 INFO - PROCESS | 6457 | _x_x_mozilla_cycle_collect,1075 22:02:44 INFO - PROCESS | 6457 | __end_cc_report 22:02:44 INFO - PROCESS | 6457 | __startTimestamp1472187764026__endTimestamp 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | ------- Summary: start ------- 22:02:44 INFO - PROCESS | 6457 | Number of tests: 6 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#0] dojo.html Cycles:165 Average:5614.38 Median:5870.03 stddev:644.49 (11.0%) stddev-sans-first:618.73 22:02:44 INFO - PROCESS | 6457 | Values: 3230.3 3256.0 3256.7 3285.1 3309.4 5526.1 5618.0 5677.5 5683.2 5685.2 5642.0 5721.1 5724.5 5724.8 5767.2 4976.2 5136.0 5176.5 5218.1 5256.3 5015.8 5083.2 5122.4 5129.1 5130.8 5049.3 5098.7 5125.2 5130.9 5155.5 5188.2 5197.0 5199.4 5206.9 5232.5 4971.7 5052.3 5066.4 5109.8 5116.5 5551.5 5570.8 5586.4 5615.3 5624.2 6105.9 6116.5 6117.8 6122.7 6136.7 5980.6 6025.6 6036.2 6101.9 6136.1 5658.9 5703.9 5712.4 5716.7 5716.9 5965.7 6205.4 6207.3 6214.5 6216.7 5818.4 5834.7 5847.4 5952.8 5961.9 6020.6 6020.8 6023.6 6045.7 6055.2 5747.6 5962.3 5978.0 5980.0 5986.2 5913.2 5944.8 5949.3 5995.4 6049.0 4620.8 4624.5 4624.7 4626.0 4655.8 4025.2 4126.1 4137.4 4145.4 4147.6 5649.1 5662.8 5669.9 5728.3 5763.0 5894.2 5935.9 5939.2 5980.8 6001.4 5633.8 5727.8 5740.8 5749.2 5751.0 5090.4 5101.3 5136.3 5173.7 5214.1 6401.8 6411.2 6412.9 6414.7 6418.8 5887.9 6156.8 6174.4 6176.8 6178.5 6115.2 6166.9 6178.1 6223.5 6242.4 5916.8 5974.4 5981.3 5982.8 6019.5 5813.9 6073.2 6079.4 6080.5 6095.2 5894.4 5901.0 5942.2 5998.0 6028.8 5909.2 5947.0 5960.0 5969.9 5971.9 5692.3 5836.7 5874.6 5885.0 5894.2 5907.4 5958.4 5961.8 6044.0 6078.9 5870.0 5873.2 5877.4 5877.7 5880.2 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#1] ext.html Cycles:180 Average:66655.89 Median:210408.64 stddev:136075.57 (64.7%) stddev-sans-first:136366.98 22:02:44 INFO - PROCESS | 6457 | Values: 624.0 633.0 655.2 673.8 676.3 35140.6 46017.8 46487.8 46583.1 46727.5 48444.8 48824.0 48891.5 48905.8 49037.8 8457.3 9187.4 9361.1 9368.1 9377.2 8579.2 8657.4 8671.8 10897.4 11209.0 11391.8 11426.8 11430.7 11461.3 11463.5 11176.9 11214.7 11245.7 11250.6 11293.7 11023.5 11086.6 11090.3 11101.4 11101.4 45965.0 46246.0 46293.8 46333.3 46350.0 3774.6 3823.0 3858.3 3924.2 4147.3 34147.0 34436.1 34624.1 34697.8 34828.8 4674.7 4722.8 4742.2 4748.2 4829.3 393224.0 393650.0 394042.0 394584.0 394938.0 64364.7 64596.4 64697.0 64775.7 64805.4 40162.8 40739.0 40817.0 43296.4 66610.3 16811.6 16921.6 16946.6 16957.9 16969.3 11601.1 12629.1 12688.7 12729.7 12730.2 20294.6 20869.7 21005.8 21838.1 21978.7 7590.4 7762.9 7848.4 7857.6 7920.0 1079.0 1089.8 1091.7 1105.5 1110.2 34684.7 35074.6 35641.0 35685.1 35989.1 1120.4 1176.3 1176.3 1221.0 1254.8 18461.8 18794.2 18931.8 18949.5 18973.3 655738.0 660477.0 661568.0 662815.0 663567.0 41738.4 41851.6 41903.0 41928.0 42018.4 1292.7 1405.8 1418.0 1431.2 1450.2 34190.0 36322.6 36525.6 36673.0 37028.3 54239.0 54561.5 54651.5 54653.7 54667.2 416670.0 417825.0 417883.0 418320.0 518636.0 46258.5 46700.5 47186.3 47489.0 47573.5 52752.0 53078.5 53135.5 53432.5 54323.7 43632.0 45145.1 45583.0 46422.5 47762.8 44608.6 44847.0 46559.8 49565.8 49596.5 45469.8 46493.5 46875.3 46905.8 47052.0 38552.8 40985.6 41251.0 41297.0 41416.8 30738.5 30874.5 30903.5 31025.0 31238.0 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#2] jquery.html Cycles:180 Average:32756.97 Median:2107.18 stddev:98229.94 (4661.7%) stddev-sans-first:98475.43 22:02:44 INFO - PROCESS | 6457 | Values: 389.3 409.6 425.7 432.2 433.7 15337.3 20400.5 20714.2 20793.3 20796.7 19614.1 19699.8 19725.1 19737.0 19949.9 5830.5 5918.9 5968.8 5998.5 6012.4 5911.2 5954.6 5977.6 5979.8 5984.8 5553.7 5769.2 5811.5 5888.1 5888.8 5870.0 6045.4 6402.5 6456.1 6580.6 5813.8 5819.6 5987.6 6002.2 6003.2 14399.3 14703.4 14765.9 14772.9 21571.8 2446.6 2604.9 2614.2 2680.3 3077.8 10529.9 10715.8 10819.9 10863.8 10864.0 1962.6 1979.8 2019.1 2087.0 2135.9 86870.0 89637.0 90572.0 91356.0 92772.0 11291.3 11389.3 11601.4 11692.8 11703.4 16191.4 16403.5 16432.3 16451.4 16462.9 7649.6 7742.9 7767.7 7836.8 7929.4 7583.9 7600.2 7706.7 8025.4 8040.5 4931.0 4954.8 4998.7 5028.4 5177.1 2070.3 2102.7 2111.7 2120.8 2182.2 798.7 1016.2 1029.8 1056.0 1127.8 19972.3 20086.4 20428.2 20451.3 20520.1 1033.7 1054.0 1083.7 1094.6 1098.5 11056.9 11446.7 11478.4 11585.1 11683.4 504948.0 583398.0 596903.0 638819.0 647445.0 96583.0 99102.5 100010.0 100936.0 105527.0 994.7 1044.9 1051.8 1054.0 1055.0 17227.2 17427.9 17632.6 17917.4 17928.0 44194.8 44224.3 45840.5 46458.1 46598.3 64100.0 65169.7 65516.7 65809.0 67431.0 8660.2 8713.7 8879.3 8885.8 8968.0 13189.1 13404.4 13546.3 14170.6 14190.0 10882.0 11506.7 11545.0 11988.4 12079.9 11845.2 11886.5 12041.2 12169.4 12430.3 12048.3 12095.8 12134.2 12373.4 12579.4 7724.2 7972.7 8550.7 8573.5 8968.8 11788.1 12152.0 12166.8 12194.1 12211.7 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#3] mootools.html Cycles:180 Average:6280.57 Median:4396.45 stddev:3670.25 (83.5%) stddev-sans-first:3662.96 22:02:44 INFO - PROCESS | 6457 | Values: 1499.5 1584.8 1745.9 1855.9 1916.5 8422.5 9135.0 9156.6 9877.5 9878.4 9208.2 9536.1 9562.5 9577.4 9599.8 2415.5 2434.5 2437.5 2442.3 2445.4 2142.8 2304.9 2378.4 2421.3 2436.7 2428.8 2457.1 2459.7 2463.7 2465.0 2362.1 2377.1 2379.2 2386.8 2388.3 2387.6 2388.2 2407.9 2417.9 2422.6 9638.2 9672.7 9732.7 9772.5 9777.4 5576.4 5686.2 5733.9 5741.5 5741.9 3357.5 3435.1 3453.0 3463.4 3487.3 1106.4 1184.7 1206.2 1238.0 1254.9 13607.6 13613.7 13614.0 13617.9 13629.4 4836.0 5065.2 5072.8 5124.9 5146.6 11480.7 11486.7 11486.8 11487.6 11490.9 5741.8 5755.3 5759.3 5764.0 5768.7 5092.1 5193.3 5269.3 5277.2 5330.8 4072.6 4161.4 4177.9 4224.9 4329.7 1113.4 1216.1 1248.9 1254.5 1259.3 1003.6 1041.4 1046.4 1055.9 1061.3 6090.9 6231.3 6373.9 6399.4 6433.0 1029.9 1045.2 1051.2 1062.1 1092.5 4332.5 4460.4 4484.1 4538.4 4578.0 11817.6 12202.2 12241.5 12244.4 12250.2 11326.0 11748.9 12187.3 12188.9 12194.0 7985.5 8152.1 8161.1 8162.6 8164.3 6622.5 6692.4 6745.2 6763.9 6892.8 10927.6 11235.2 11244.9 11398.9 11410.9 11131.2 11398.0 11435.8 11555.7 11561.2 8435.4 8560.6 8629.9 8634.7 8649.6 9271.5 9281.7 9347.6 9350.5 9432.5 6564.0 8052.0 8101.5 8186.1 8233.2 8298.0 8322.3 8341.5 8341.9 8569.1 7847.2 7888.1 7988.0 8092.6 8094.4 4787.5 4834.9 4864.6 4882.9 4959.2 5446.2 5487.9 5512.2 5557.7 5562.4 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#4] prototype.html Cycles:180 Average:4896.79 Median:5549.60 stddev:1437.24 (25.9%) stddev-sans-first:1399.93 22:02:44 INFO - PROCESS | 6457 | Values: 336.9 347.6 347.8 357.6 358.9 5560.2 5617.4 5637.3 5674.8 5683.3 5523.2 5544.7 5554.5 5560.6 5580.0 3922.5 4159.3 4345.3 4379.7 4389.1 4351.0 4364.7 4373.4 4388.9 4389.0 4339.7 4363.7 4374.5 4375.7 4394.8 4174.6 4236.0 4380.5 4384.2 4402.4 3857.6 3867.7 3872.8 3877.8 3915.2 5409.4 5421.4 5446.6 5459.6 5470.5 4817.1 4976.5 4990.3 5016.7 5037.3 5832.9 5836.2 5842.9 5862.4 5880.9 4301.7 4311.8 4317.6 4324.7 4331.4 5723.7 5745.5 5876.8 6059.5 6090.1 4165.0 4169.5 4273.2 4317.7 4320.2 4362.3 4365.5 4375.3 4378.5 4380.6 4841.8 4907.2 4970.0 4971.1 4980.7 5848.8 5849.2 5853.3 5889.6 5892.3 1482.0 1509.7 1512.4 1513.8 1514.0 923.5 954.3 958.3 960.7 975.0 5049.3 5072.5 5095.0 5109.3 5125.5 5293.3 5313.7 5321.0 5364.2 5364.9 5037.7 5066.7 5082.3 5342.7 5793.3 3186.7 3200.6 3209.8 3211.0 3212.2 6132.8 6273.2 6281.4 6285.8 6318.9 6077.7 6079.2 6086.7 6096.8 6136.4 6210.2 6233.2 6234.8 6252.3 6348.3 5790.5 5983.9 6018.5 6034.4 6121.2 5922.4 5931.8 5934.3 5947.4 5950.4 5809.4 5990.9 6023.9 6192.2 6261.4 4285.0 4297.2 4304.4 4307.3 4307.8 6001.6 6013.8 6074.4 6079.9 6083.7 5646.8 5874.4 5935.0 5944.2 5975.9 5921.8 5922.5 5924.2 5930.8 5937.2 5641.4 5847.4 5850.3 5860.3 5885.0 5910.6 5946.8 5955.2 5972.9 5973.0 5777.6 5803.1 5814.9 5817.3 5823.7 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - PROCESS | 6457 | [#5] yui.html Cycles:180 Average:24055.61 Median:15063.09 stddev:35933.15 (238.6%) stddev-sans-first:35991.70 22:02:44 INFO - PROCESS | 6457 | Values: 848.0 887.8 904.7 917.6 941.1 17226.8 17840.9 17854.6 17858.6 17874.7 16503.0 16649.6 16662.9 16669.5 16695.7 737.4 757.3 757.8 758.4 869.3 880.8 887.5 891.4 891.7 891.8 931.9 941.6 942.5 942.6 943.5 936.6 936.9 937.3 937.4 938.4 558.4 578.0 579.7 691.7 696.9 14118.5 14515.4 14540.6 15017.4 15036.5 16084.2 16645.3 16762.1 17016.6 17041.6 12860.7 12985.4 13110.4 13138.3 13217.5 4102.9 4152.5 4187.9 4198.0 4199.6 89900.6 89990.6 89996.0 90324.0 91852.5 12745.0 12864.1 12915.2 13052.3 13073.9 25394.2 25439.1 25706.7 26093.3 26238.5 9089.5 9104.0 9546.4 9627.2 9778.3 5145.1 5422.2 5460.4 5498.6 5568.7 3887.7 4014.9 4015.6 4021.8 4028.9 659.0 670.6 673.1 680.1 683.5 720.3 723.6 774.1 797.6 812.6 9654.7 10690.3 10948.8 10981.3 10992.0 743.0 748.0 763.1 769.9 780.2 5138.6 5325.5 5349.3 5354.4 5402.3 130438.0 135779.3 137257.0 139011.0 144630.0 110639.4 114916.0 116128.0 117471.0 118864.4 77691.0 85412.6 108328.0 110018.0 113544.4 9600.5 10015.1 10100.8 10138.1 10141.6 59544.0 60571.7 61311.4 62907.0 63375.4 93999.5 94118.0 94976.5 96355.5 96430.5 13439.4 13713.3 13848.5 13922.4 14033.3 14817.0 15045.7 15069.4 16098.2 16356.1 13198.1 13300.9 13823.7 14128.2 14332.1 14124.6 14132.4 14339.4 14373.7 14603.9 13222.9 13387.9 13478.1 13500.1 13549.9 10619.3 10891.5 11038.8 11131.9 11475.4 11099.0 11301.2 11309.2 11348.2 11527.0 22:02:44 INFO - PROCESS | 6457 | -------- Summary: end -------- 22:02:44 INFO - PROCESS | 6457 | 22:02:44 INFO - TEST-INFO | 6457: exit 0 22:02:44 INFO - TEST-OK | dromaeo_css | took 1114494ms 22:02:44 INFO - TEST-START | kraken 22:02:44 INFO - operating with platform_type : linux_ 22:02:44 INFO - Initialising browser for kraken test... 22:02:44 INFO - TEST-INFO | started process 6665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWMHecm/profile http://localhost:37377/getInfo.html) 22:02:49 INFO - PROCESS | 6665 | __metrics Screen width/height:1600/1200 22:02:49 INFO - PROCESS | 6665 | colorDepth:24 22:02:49 INFO - PROCESS | 6665 | Browser inner width/height: 1024/697 22:02:49 INFO - PROCESS | 6665 | __metrics 22:02:50 INFO - PROCESS | 6665 | 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" 22:02:50 INFO - PROCESS | 6665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:51 INFO - TEST-INFO | 6665: exit 0 22:02:51 INFO - Browser initialized. 22:02:51 INFO - Running cycle 1/1 for kraken test... 22:02:51 INFO - TEST-INFO | started process 6753 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWMHecm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:03:03 INFO - PROCESS | 6753 | RSS: Main: 145346560 22:03:03 INFO - PROCESS | 6753 | 22:03:05 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37377/tests/kraken/driver.html?testName=audio-beat-detection) 22:03:06 INFO - PROCESS | 6753 | RSS: Main: 191037440 22:03:06 INFO - PROCESS | 6753 | 22:03:09 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37377/tests/kraken/driver.html?testName=audio-dft) 22:03:09 INFO - PROCESS | 6753 | RSS: Main: 181882880 22:03:09 INFO - PROCESS | 6753 | 22:03:12 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37377/tests/kraken/driver.html?testName=audio-fft) 22:03:13 INFO - PROCESS | 6753 | RSS: Main: 271196160 22:03:13 INFO - PROCESS | 6753 | 22:03:15 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37377/tests/kraken/driver.html?testName=audio-oscillator) 22:03:15 INFO - PROCESS | 6753 | RSS: Main: 175996928 22:03:15 INFO - PROCESS | 6753 | 22:03:18 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:03:18 INFO - PROCESS | 6753 | RSS: Main: 176705536 22:03:18 INFO - PROCESS | 6753 | 22:03:21 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37377/tests/kraken/driver.html?testName=imaging-darkroom) 22:03:22 INFO - PROCESS | 6753 | RSS: Main: 212680704 22:03:22 INFO - PROCESS | 6753 | 22:03:25 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37377/tests/kraken/driver.html?testName=imaging-desaturate) 22:03:26 INFO - PROCESS | 6753 | RSS: Main: 211902464 22:03:26 INFO - PROCESS | 6753 | 22:03:29 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37377/tests/kraken/driver.html?testName=json-parse-financial) 22:03:29 INFO - PROCESS | 6753 | RSS: Main: 211927040 22:03:29 INFO - PROCESS | 6753 | 22:03:31 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:03:31 INFO - PROCESS | 6753 | RSS: Main: 176644096 22:03:31 INFO - PROCESS | 6753 | 22:03:33 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:03:34 INFO - PROCESS | 6753 | RSS: Main: 182046720 22:03:34 INFO - PROCESS | 6753 | 22:03:35 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:03:36 INFO - PROCESS | 6753 | RSS: Main: 186068992 22:03:36 INFO - PROCESS | 6753 | 22:03:39 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:03:39 INFO - PROCESS | 6753 | RSS: Main: 180293632 22:03:39 INFO - PROCESS | 6753 | 22:03:42 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:03:43 INFO - PROCESS | 6753 | RSS: Main: 184553472 22:03:43 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | Cycle 1(1): loaded http://localhost:37377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37377/tests/kraken/driver.html?testName=ai-astar) 22:03:44 INFO - PROCESS | 6753 | RSS: Main: 235147264 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | __start_tp_report 22:03:44 INFO - PROCESS | 6753 | _x_x_mozilla_page_load 22:03:44 INFO - PROCESS | 6753 | _x_x_mozilla_page_load_details 22:03:44 INFO - PROCESS | 6753 | |i|pagename|runs| 22:03:44 INFO - PROCESS | 6753 | |0;ai-astar;101;110;110;112;110;110;110;111;110;110 22:03:44 INFO - PROCESS | 6753 | |1;audio-beat-detection;145;117;116;170;116;116;142;117;146;115 22:03:44 INFO - PROCESS | 6753 | |2;audio-dft;162;160;160;159;160;162;162;161;161;160 22:03:44 INFO - PROCESS | 6753 | |3;audio-fft;76;76;83;113;75;76;74;74;96;74 22:03:44 INFO - PROCESS | 6753 | |4;audio-oscillator;82;83;123;83;82;83;119;82;83;130 22:03:44 INFO - PROCESS | 6753 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;116;116 22:03:44 INFO - PROCESS | 6753 | |6;imaging-darkroom;145;145;146;146;146;145;146;145;144;146 22:03:44 INFO - PROCESS | 6753 | |7;imaging-desaturate;94;95;95;95;95;94;94;94;94;95 22:03:44 INFO - PROCESS | 6753 | |8;json-parse-financial;50;50;52;50;49;49;51;51;50;48 22:03:44 INFO - PROCESS | 6753 | |9;json-stringify-tinderbox;60;61;60;60;60;60;60;61;60;60 22:03:44 INFO - PROCESS | 6753 | |10;stanford-crypto-aes;73;73;72;81;72;72;72;73;72;76 22:03:44 INFO - PROCESS | 6753 | |11;stanford-crypto-ccm;117;123;123;123;122;116;124;116;198;118 22:03:44 INFO - PROCESS | 6753 | |12;stanford-crypto-pbkdf2;159;151;151;153;157;151;154;155;146;152 22:03:44 INFO - PROCESS | 6753 | |13;stanford-crypto-sha256-iterative;62;61;62;60;63;60;60;60;60;68 22:03:44 INFO - PROCESS | 6753 | __end_tp_report 22:03:44 INFO - PROCESS | 6753 | __start_cc_report 22:03:44 INFO - PROCESS | 6753 | _x_x_mozilla_cycle_collect,1598 22:03:44 INFO - PROCESS | 6753 | __end_cc_report 22:03:44 INFO - PROCESS | 6753 | __startTimestamp1472187824888__endTimestamp 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | ------- Summary: start ------- 22:03:44 INFO - PROCESS | 6753 | Number of tests: 14 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:3.03 (2.8%) stddev-sans-first:0.71 22:03:44 INFO - PROCESS | 6753 | Values: 101.0 110.0 110.0 112.0 110.0 110.0 110.0 111.0 110.0 110.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:129.50 stddev:19.37 (15.0%) stddev-sans-first:19.77 22:03:44 INFO - PROCESS | 6753 | Values: 145.0 117.0 116.0 170.0 116.0 116.0 142.0 117.0 146.0 115.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 22:03:44 INFO - PROCESS | 6753 | Values: 162.0 160.0 160.0 159.0 160.0 162.0 162.0 161.0 161.0 160.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:12.94 (17.0%) stddev-sans-first:13.56 22:03:44 INFO - PROCESS | 6753 | Values: 76.0 76.0 83.0 113.0 75.0 76.0 74.0 74.0 96.0 74.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:82.50 stddev:20.19 (24.5%) stddev-sans-first:20.86 22:03:44 INFO - PROCESS | 6753 | Values: 82.0 83.0 123.0 83.0 82.0 83.0 119.0 82.0 83.0 130.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:03:44 INFO - PROCESS | 6753 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 22:03:44 INFO - PROCESS | 6753 | Values: 145.0 145.0 146.0 146.0 146.0 145.0 146.0 145.0 144.0 146.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:03:44 INFO - PROCESS | 6753 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.15 (2.3%) stddev-sans-first:1.22 22:03:44 INFO - PROCESS | 6753 | Values: 50.0 50.0 52.0 50.0 49.0 49.0 51.0 51.0 50.0 48.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:03:44 INFO - PROCESS | 6753 | Values: 60.0 61.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:73.00 stddev:2.88 (3.9%) stddev-sans-first:3.04 22:03:44 INFO - PROCESS | 6753 | Values: 73.0 73.0 72.0 81.0 72.0 72.0 72.0 73.0 72.0 76.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#11] stanford-crypto-ccm Cycles:10 Average:128.00 Median:123.00 stddev:24.80 (20.2%) stddev-sans-first:25.98 22:03:44 INFO - PROCESS | 6753 | Values: 117.0 123.0 123.0 123.0 122.0 116.0 124.0 116.0 198.0 118.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.90 Median:153.50 stddev:3.63 (2.4%) stddev-sans-first:3.11 22:03:44 INFO - PROCESS | 6753 | Values: 159.0 151.0 151.0 153.0 157.0 151.0 154.0 155.0 146.0 152.0 22:03:44 INFO - PROCESS | 6753 | 22:03:44 INFO - PROCESS | 6753 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:61.50 stddev:2.50 (4.1%) stddev-sans-first:2.65 22:03:44 INFO - PROCESS | 6753 | Values: 62.0 61.0 62.0 60.0 63.0 60.0 60.0 60.0 60.0 68.0 22:03:44 INFO - PROCESS | 6753 | -------- Summary: end -------- 22:03:44 INFO - PROCESS | 6753 | 22:03:45 INFO - TEST-INFO | 6753: exit 0 22:03:45 INFO - TEST-OK | kraken | took 61064ms 22:03:45 INFO - SUITE-END | took 1175s 22:03:46 INFO - Completed test suite (00:19:35) 22:03:46 INFO - javascript benchmark 22:03:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.2731885325798, 3256.039468356835, 3256.7394608431314, 3285.080177550287, 3309.4043072247, 5526.143447765598, 5618.0, 5677.545796336293, 5683.175939488767, 5685.232493613361, 5642.0, 5721.056025755745, 5724.542036637069, 5724.7996320128805, 5767.1637612546265, 4976.203807390818, 5136.02415541047, 5176.456472070435, 5218.139007063849, 5256.26412302278, 5015.824446144386, 5083.18669012958, 5122.385313762354, 5129.051125541787, 5130.820421285256, 5049.318342023835, 5098.719570423644, 5125.2312153176945, 5130.9230361544605, 5155.510226528474, 5188.247704082924, 5197.038547868657, 5199.376074871021, 5206.895862082775, 5232.529072383504, 4971.651984361096, 5052.343195384613, 5066.417362003343, 5109.846704598868, 5116.488351164854, 5551.528120109796, 5570.805021824257, 5586.385497595261, 5615.326160860723, 5624.21261023457, 6105.948836118943, 6116.541259405518, 6117.8112229642, 6122.693865306762, 6136.69316534176, 5980.551458640576, 6025.638461692269, 6036.15493830864, 6101.90753056428, 6136.110264011693, 5658.886822263532, 5703.914441283384, 5712.371639119693, 5716.742746576414, 5716.8856622867725, 5965.701714914237, 6205.410486003822, 6207.286162091417, 6214.471769899519, 6216.658083805343, 5818.44724751148, 5834.737436815353, 5847.415258474119, 5952.76188952437, 5961.867245223394, 6020.608660437145, 6020.849478762978, 6023.608465449732, 6045.697715114226, 6055.182550355646, 5747.597668163272, 5962.34414214436, 5977.970110149421, 5980.0, 5986.191864098378, 5913.231279933581, 5944.821655350346, 5949.25634295713, 5995.430434108753, 6049.0, 4620.768961551909, 4624.514425985223, 4624.65315101362, 4626.028534007829, 4655.767211639404, 4025.2150830587752, 4126.051008268115, 4137.441445404832, 4145.440365550672, 4147.605977432139, 5649.096144616841, 5662.8301150965535, 5669.943300566942, 5728.255326807488, 5763.0202865512965, 5894.233749612523, 5935.910961335583, 5939.198208241918, 5980.790672326448, 6001.399860014051, 5633.774649014075, 5727.77088916447, 5740.799072032459, 5749.1663708762835, 5750.971245143747, 5090.363704536933, 5101.285819985205, 5136.332276803992, 5173.663711858792, 5214.139666954986, 6401.807945761635, 6411.166548348685, 6412.935870641234, 6414.679266036773, 6418.84460797061, 5887.911681324869, 6156.78451254204, 6174.413430724074, 6176.81469555914, 6178.536609754196, 6115.1794953435965, 6166.907496387468, 6178.073289006685, 6223.471004964447, 6242.406971337716, 5916.763329466945, 5974.402559744165, 5981.282246130492, 5982.820515384546, 6019.548533860066, 5813.883722325614, 6073.180120683828, 6079.361667024898, 6080.513558915364, 6095.207623008981, 5894.381089985662, 5901.026330655565, 5942.227510423617, 5997.970010150096, 6028.849278768066, 5909.23179986599, 5946.989011867906, 5960.0, 5969.850753731192, 5971.940280597312, 5692.31692196939, 5836.737346819318, 5874.5887787856145, 5885.0, 5894.204282421818, 5907.438793314628, 5958.374370691015, 5961.791337303173, 6043.969780151071, 6078.939210607838, 5870.031444811559, 5873.236479257669, 5877.4122587740885, 5877.706114694162, 5880.206172166874], "value": 5570.516161939425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [623.9937600623992, 633.0125004992212, 655.171208421347, 673.7843889955217, 676.3067855448166, 35140.64859351406, 46017.76991115044, 46487.767561162145, 46583.06833863321, 46727.53272467275, 48444.75777621107, 48824.0, 48891.51108488923, 48905.7554712226, 49037.75481122598, 8457.323414126886, 9187.402818816772, 9361.110694484014, 9368.110029547182, 9377.202937750266, 8579.227869491744, 8657.350698697592, 8671.783205419884, 10897.400642964674, 11209.0, 11391.829122563167, 11426.771464570746, 11430.657080287563, 11461.255018423817, 11463.541458341699, 11176.944115279453, 11214.66356009321, 11245.662630121109, 11250.606228781953, 11293.661190164308, 11023.503942322615, 11086.61196858106, 11090.279131856403, 11101.44492775358, 11101.44492775358, 45965.0, 46246.0, 46293.76853115747, 46333.30500042502, 46350.0, 3774.6036666149935, 3822.961770382316, 3858.266929283427, 3924.2347742190286, 4147.274227010261, 34147.0, 34436.139096522784, 34624.13439664003, 34697.82651086753, 34828.82585587081, 4674.719516828967, 4722.834700785456, 4742.241241401359, 4748.19280722278, 4829.323894654751, 393224.0, 393650.0, 394042.0, 394584.0, 394938.0, 64364.678176609756, 64596.35403645904, 64697.0, 64775.67612161909, 64805.35194647993, 40162.79918600388, 40739.0, 40817.0, 43296.3505547417, 66610.3338966604, 16811.57971050709, 16921.561667258175, 16946.57633559146, 16957.915210423867, 16969.321227151024, 11601.07191424684, 12629.052821038458, 12688.74622507563, 12729.68175795594, 12730.17253878495, 20294.594108117755, 20869.68695469569, 21005.789942100688, 21838.14375778045, 21978.670319945213, 7590.354819840265, 7762.91790291878, 7848.411369147337, 7857.646405911748, 7919.960400197962, 1078.962236321725, 1089.8201796703456, 1091.6670415523183, 1105.4859490337033, 1110.245033377295, 34684.653153468644, 35074.64925350714, 35641.0, 35685.107872302884, 35989.1002724934, 1120.3781901045, 1176.264834478451, 1176.2824676947055, 1221.035382048172, 1254.7804134276464, 18461.81538184601, 18794.154263058193, 18931.810681893006, 18949.526261843563, 18973.33593324227, 655738.0, 660477.0, 661568.0, 662815.0, 663567.0, 41738.373924391155, 41851.58148418477, 41903.0, 41928.0, 42018.369724454154, 1292.65744577687, 1405.8242719660043, 1418.0215651200638, 1431.2199851081186, 1450.245872146499, 34190.0, 36322.636773632985, 36525.63474365223, 36673.0, 37028.259434810614, 54239.0, 54561.45438545564, 54651.45348546463, 54653.72673136609, 54667.179992300946, 416670.0, 417825.0, 417883.0, 418320.0, 518636.0, 46258.53741462677, 46700.53299466962, 47186.29220561763, 47489.0, 47573.52426475829, 52752.0, 53078.469215307356, 53135.4686453146, 53432.465675344305, 54323.72838135784, 43632.02268148897, 45145.09709805851, 45583.0, 46422.53577464317, 47762.76118619385, 44608.55391446044, 44847.0, 46559.76720116378, 49565.75217123891, 49596.50403495919, 45469.77265113653, 46493.53506464892, 46875.29687054765, 46905.76547117379, 47052.0, 38552.80723596364, 40985.59014409937, 41251.0, 41297.0, 41416.79291603523, 30738.538921916635, 30874.53688194724, 30903.536446953764, 31025.0, 31238.0], "value": 21957.387132543234, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.2740039825726, 409.61291579457423, 425.72753437799804, 432.22415763704146, 433.7072476078646, 15337.309821058052, 20400.489987750312, 20714.171433142656, 20793.272235471763, 20796.688049679247, 19614.117364718586, 19699.803001969976, 19725.112369943352, 19737.0, 19949.900250498762, 5830.475257226849, 5918.911216331759, 5968.7612495500125, 5998.460138587527, 6012.398760123996, 5911.172435858962, 5954.553408494358, 5977.5815692901515, 5979.820605381824, 5984.790532331369, 5553.72231388431, 5769.16347129666, 5811.535077193836, 5888.116782482619, 5888.793892213774, 5870.0, 6045.365236650155, 6402.487800975934, 6456.0961465394885, 6580.638064906429, 5813.82558523245, 5819.621724587931, 5987.550933679991, 6002.159697642333, 6003.249593800775, 14399.280035998157, 14703.411863525445, 14765.852341476662, 14772.852271477363, 21571.784282157136, 2446.6330050492393, 2604.9479010419873, 2614.2157352794275, 2680.3433158876005, 3077.753779697619, 10529.947350263199, 10715.83926241107, 10819.891801081967, 10863.83704244437, 10864.0, 1962.5584243545088, 1979.7822239553639, 2019.0611365715013, 2086.9791302086937, 2135.9359219223447, 86870.0, 89637.0, 90572.0, 91356.0, 92772.0, 11291.266067705574, 11389.259698119597, 11601.361925094032, 11692.766144677058, 11703.414829258503, 16191.352345906032, 16403.507894763177, 16432.342706291853, 16451.42420015318, 16462.917685411736, 7649.579273140031, 7742.922570774333, 7767.689292428239, 7836.764897053097, 7929.44493885434, 7583.886241706379, 7600.239976002355, 7706.691732330644, 8025.438219324708, 8040.4773689711155, 4931.0, 4954.826581069645, 4998.650094493423, 5028.446870844205, 5177.068127737043, 2070.254708305009, 2102.6845973104155, 2111.6832475128545, 2120.7985241402043, 2182.2362173239558, 798.6941350891218, 1016.1768967136643, 1029.8352263637933, 1056.0390045059114, 1127.7575321305958, 19972.300969466, 20086.39740807778, 20428.18287268522, 20451.28420505275, 20520.07659655319, 1033.7260625934134, 1054.0, 1083.7399024234285, 1094.6332978452149, 1098.5441041967654, 11056.944715276533, 11446.656600302003, 11478.426078696031, 11585.073194144448, 11683.415829208505, 504948.0, 583398.0, 596903.0, 638819.0, 647445.0, 96583.0, 99102.5044874771, 100010.0, 100936.0, 105527.0, 994.6518718448485, 1044.8589440425596, 1051.8317069269033, 1054.0408228512017, 1055.0188324857957, 17227.22477488491, 17427.912860435616, 17632.647347053244, 17917.37289194872, 17928.01395923237, 44194.77902610466, 44224.33663494986, 45840.54159458363, 46458.07083858371, 46598.30102548532, 64100.0, 65169.67415162894, 65516.672416637615, 65809.0, 67431.0, 8660.220580147818, 8713.651453941771, 8879.289656827568, 8885.822283554422, 8968.013518512827, 13189.142705724098, 13404.396802144121, 13546.254955977516, 14170.645733856323, 14190.0, 10881.960511915962, 11506.712332191762, 11544.960953514224, 11988.400579971141, 12079.879201208227, 11845.23006004607, 11886.524539018343, 12041.157119001586, 12169.391530423267, 12430.316332601793, 12048.337341446304, 12095.818562721743, 12134.211276267015, 12373.443195056425, 12579.371031448574, 7724.227577242231, 7972.720954766555, 8550.74347769568, 8573.485590864568, 8968.775780605538, 11788.115891308014, 12152.0, 12166.783321667763, 12194.146409751233, 12211.694707632025], "value": 9589.016735150097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1499.5126583860244, 1584.8335924727903, 1745.9301627934883, 1855.8728542406038, 1916.4633902507294, 8422.494650320985, 9134.954325228373, 9156.58795354209, 9877.456739879295, 9878.357906736057, 9208.217301529377, 9536.141747242747, 9562.521873906311, 9577.425354478737, 9599.808003839937, 2415.5168966206743, 2434.500927309897, 2437.5003124359464, 2442.3283597010877, 2445.40087678519, 2142.828573714099, 2304.9078036878504, 2378.369732021016, 2421.3462365161395, 2436.6954130733657, 2428.781409673129, 2457.066314800373, 2459.692538432696, 2463.6674049003336, 2465.0263146057296, 2362.137819695807, 2377.072941552796, 2379.155399833069, 2386.78518933296, 2388.2715771689627, 2387.582173119697, 2388.1641425500934, 2407.9398015049587, 2417.9395515112265, 2422.5881600127846, 9638.180754635865, 9672.661456849048, 9732.659356922544, 9772.462514561696, 9777.364471309342, 5576.414476479952, 5686.2039314496005, 5733.885322293572, 5741.454561816662, 5741.88516229673, 3357.5467311912826, 3435.12404336895, 3453.0, 3463.428534291839, 3487.2851065531504, 1106.3748981825242, 1184.6919800851754, 1206.215959626241, 1238.0405185980846, 1254.943527541263, 13607.59177224675, 13613.727725445435, 13614.0, 13617.931910340385, 13629.386677599532, 4835.975820120947, 5065.214891691794, 5072.82245121419, 5124.923126153036, 5146.639735218499, 11480.655580332603, 11486.712832179095, 11486.770264594661, 11487.597934072268, 11490.885091149148, 5741.770329186786, 5755.309362876482, 5759.308882934075, 5763.971180144073, 5768.74040668171, 5092.083424983539, 5193.298904647824, 5269.341332333458, 5277.23480095384, 5330.813421530305, 4072.6378700353725, 4161.375793630979, 4177.874663760092, 4224.9155016899485, 4329.653627709776, 1113.365381732421, 1216.0636310041218, 1248.8626251112553, 1254.4919307680404, 1259.3199672177104, 1003.5885287032281, 1041.4011943132577, 1046.4140081554353, 1055.8521806947033, 1061.310148403544, 6090.908636370458, 6231.283402408691, 6373.8725225496155, 6399.392057754506, 6433.035044743232, 1029.8609687692215, 1045.2265323660436, 1051.2063392138887, 1062.0972173652488, 1092.5192915117482, 4332.458442694663, 4460.442444694413, 4484.080763443486, 4538.364628951949, 4578.0157266187925, 11817.586384476503, 12202.206856554472, 12241.510339586495, 12244.448999795031, 12250.203736757083, 11326.0, 11748.88251117495, 12187.329696866758, 12188.878111218952, 12193.96351310131, 7985.5208687478935, 8152.144024877302, 8161.143079976636, 8162.591870406574, 8164.265216130577, 6622.503312251697, 6692.431143352869, 6745.156855393076, 6763.864722705617, 6892.827679308058, 10927.617533386294, 11235.213535052468, 11244.943775281072, 11398.94300528492, 11410.94294528522, 11131.218120171625, 11398.031167350868, 11435.771284574428, 11555.711107222387, 11561.248518846249, 8435.409521333448, 8560.57197140153, 8629.913700862911, 8634.654613815377, 8649.610767515604, 9271.490068046322, 9281.67514137002, 9347.579358928728, 9350.485723285285, 9432.52837358143, 6563.96718016426, 8052.0, 8101.473404228706, 8186.058603260702, 8233.17668233172, 8297.95851020741, 8322.292605128632, 8341.457805242882, 8341.874871876806, 8569.10024447424, 7847.176046515034, 7888.053433587776, 7988.041435027834, 8092.635831387484, 8094.3929205311015, 4787.54518320759, 4834.903301934012, 4864.562189402971, 4882.877928051685, 4959.181735013683, 5446.210299506629, 5487.862803429786, 5512.228288039598, 5557.749901254372, 5562.443755624567], "value": 4973.515391219845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8888266871933, 347.63845600575405, 347.7774224496323, 357.5798436836718, 358.93359728450224, 5560.165975103731, 5617.382087970325, 5637.267155269814, 5674.829755107343, 5683.318001839774, 5523.226748255248, 5544.667319960805, 5554.527865131468, 5560.638558493695, 5579.958922387298, 3922.5489068757174, 4159.313713237312, 4345.327049086109, 4379.737215767048, 4389.056352884129, 4350.97824510877, 4364.672649551297, 4373.365861950031, 4388.934165987513, 4388.956110438902, 4339.652827773786, 4363.694541382105, 4374.540673229313, 4375.737455752649, 4394.780260986938, 4174.624283814456, 4235.957640423587, 4380.474343078819, 4384.167008268419, 4402.405675233835, 3857.5756666766633, 3867.7236511950987, 3872.7869967151796, 3877.7867217303037, 3915.197384536163, 5409.377921539034, 5421.376541697677, 5446.645968012067, 5459.617826752129, 5470.5350045246205, 4817.084753896784, 4976.4525902150735, 4990.276409920578, 5016.724080175589, 5037.345145131146, 5832.854178645525, 5836.153757705151, 5842.941570584282, 5862.355140934494, 5880.9411905880825, 4301.730989358147, 4311.8059687313835, 4317.611414972636, 4324.65402767781, 4331.350297455376, 5723.742431590588, 5745.540356771448, 5876.794312199096, 6059.454839016067, 6090.147379366892, 4164.9583504165175, 4169.478815148106, 4273.209456250604, 4317.676174286941, 4320.200762858881, 4362.280223763044, 4365.454318210223, 4375.2780791169735, 4378.4745830499905, 4380.649548036149, 4841.7653498357695, 4907.1657818171, 4969.950300497021, 4971.105201063771, 4980.651354405157, 5848.771757930797, 5849.181114643953, 5853.326867410217, 5889.646621202742, 5892.322382925932, 1481.9629509262354, 1509.6527798606382, 1512.4026009726317, 1513.7577987521943, 1513.9856296281298, 923.5151545438698, 954.346272803132, 958.2860768727343, 960.7357976556503, 975.0, 5049.318342023853, 5072.492750724898, 5095.0, 5109.284700141983, 5125.46289586657, 5293.338332708411, 5313.681179129265, 5320.973395133, 5364.249005139283, 5364.8658783529945, 5037.697738135724, 5066.721330326867, 5082.33929589151, 5342.706151161646, 5793.304803423532, 3186.665400132999, 3200.5519227308196, 3209.7592680548587, 3210.9839450802597, 3212.245122396235, 6132.816015519542, 6273.215848018997, 6281.403266689657, 6285.811425657238, 6318.905216421757, 6077.665728384893, 6079.240094988127, 6086.695665216722, 6096.786612468542, 6136.447719705248, 6210.161628180138, 6233.2208473940755, 6234.812955611248, 6252.3122456530655, 6348.301686814539, 5790.507806836466, 5983.935542237921, 6018.548608854266, 6034.426729460694, 6121.173641558511, 5922.407759224043, 5931.822045338647, 5934.258217722785, 5947.375525569752, 5950.404959504015, 5809.36097029318, 5990.88018239624, 6023.879522409615, 6192.195014648067, 6261.373862613885, 4284.957150428456, 4297.205017071852, 4304.3543468479975, 4307.310830267143, 4307.763073030888, 6001.609895356963, 6013.81958541227, 6074.362191969956, 6079.90243366038, 6083.69581520931, 5646.774129034792, 5874.383189765013, 5935.0, 5944.167816505606, 5975.940240597713, 5921.822345329647, 5922.496587790086, 5924.170616113834, 5930.762769489172, 5937.150167919336, 5641.379448260606, 5847.415258474119, 5850.327212370632, 5860.326062502867, 5885.0, 5910.611006413404, 5946.762129514944, 5955.225820643289, 5972.850678733066, 5972.9701351494705, 5777.591221179524, 5803.12953057028, 5814.94185058144, 5817.331006934257, 5823.679697616672], "value": 4437.795701437731, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.9872801907973, 887.840188766022, 904.7150147703469, 917.6237742525564, 941.1341565759501, 17226.82773172268, 17840.91079544602, 17854.553636159104, 17858.553536161602, 17874.731879021794, 16503.0, 16649.583760405967, 16662.916685416585, 16669.49991500257, 16695.66608667826, 737.4395459450816, 757.3032809814983, 757.8484303139367, 758.3932853717032, 869.2871845087031, 880.7621942075635, 887.4542156573725, 891.4205766251956, 891.7369376034086, 891.754767438953, 931.9161275485205, 941.55276243784, 942.5146049784366, 942.5993952570165, 943.4952300519224, 936.5972631768337, 936.9250459963221, 937.2689302344182, 937.3532262738688, 938.3666025432806, 558.430400990991, 578.037567450195, 579.6637949988997, 691.6576294734139, 696.9442444604471, 14118.505852295222, 14515.419383224658, 14540.563783086523, 15017.399304027826, 15036.473723419627, 16084.19579021056, 16645.33418663264, 16762.07808570528, 17016.574585635335, 17041.57396065096, 12860.742785144339, 12985.35073246334, 13110.410031548603, 13138.277394743382, 13217.471301147943, 4102.856400025985, 4152.4601801765875, 4187.91624167515, 4197.958020419787, 4199.559046300155, 89900.55049724775, 89990.55004724934, 89996.0, 90324.0, 91852.54073729656, 12745.044121690726, 12864.099513034185, 12915.22508649484, 13052.282124483054, 13073.869261307455, 25394.238172854843, 25439.10963116282, 25706.742932570807, 26093.347666308495, 26238.47523049528, 9089.506710390748, 9104.0, 9546.42721436702, 9627.181689556326, 9778.315517913677, 5145.099607568661, 5422.186671999153, 5460.399356070829, 5498.560115190855, 5568.749406276727, 3887.7084218683713, 4014.879553613338, 4015.638592526628, 4021.8190181441905, 4028.899277518027, 658.980230593083, 670.6345041952147, 673.0745225315192, 680.1158493957835, 683.5283654278531, 720.326494727421, 723.6273319240595, 774.1329710724025, 797.5653268968426, 812.5937031484258, 9654.71035868925, 10690.251682382159, 10948.781024379627, 10981.286216395909, 10992.0, 743.0117943135616, 747.9626018699043, 763.0508086246728, 769.9422543309274, 780.2236774409499, 5138.563222126087, 5325.547328477045, 5349.277847490539, 5354.41101478837, 5402.324709411324, 130438.0, 135779.32110339386, 137257.0, 139011.0, 144630.0, 110639.44680276547, 114916.0, 116128.0, 117471.0, 118864.40567797105, 77691.0, 85412.57293713492, 108328.0, 110018.0, 113544.43227783973, 9600.519974001132, 10015.098641122042, 10100.797984040424, 10138.138258248131, 10141.594336226763, 59544.0, 60571.69714151401, 61311.38688613057, 62907.0, 63375.36624633879, 93999.53000235229, 94118.0, 94976.52511737397, 96355.51822240844, 96430.51784741032, 13439.395227214995, 13713.314334283046, 13848.515301964786, 13922.4431022762, 14033.29833508341, 14817.0, 15045.699086018438, 15069.397224111352, 16098.195090245676, 16356.100414477318, 13198.07613467048, 13300.866991329964, 13823.723525529233, 14128.152310861382, 14332.140071595737, 14124.576262712135, 14132.374776900788, 14339.35472903701, 14373.712525749635, 14603.92698036503, 13222.933885330513, 13387.933060334635, 13478.123921945435, 13500.054996150175, 13549.932250338685, 10619.30974486656, 10891.509882055485, 11038.779224415306, 11131.944340278247, 11475.426228688699, 11099.0, 11301.218134119217, 11309.208355415041, 11348.205625606459, 11527.0], "value": 8294.787362442188, "unit": "score"}], "name": "dromaeo_css", "value": 7738.468270573939, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 112.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 116.0, 170.0, 116.0, 116.0, 142.0, 117.0, 146.0, 115.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 159.0, 160.0, 162.0, 162.0, 161.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 83.0, 113.0, 75.0, 76.0, 74.0, 74.0, 96.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 123.0, 83.0, 82.0, 83.0, 119.0, 82.0, 83.0, 130.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 144.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 50.0, 49.0, 49.0, 51.0, 51.0, 50.0, 48.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 81.0, 72.0, 72.0, 72.0, 73.0, 72.0, 76.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 123.0, 123.0, 123.0, 122.0, 116.0, 124.0, 116.0, 198.0, 118.0], "value": 128.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 151.0, 151.0, 153.0, 157.0, 151.0, 154.0, 155.0, 146.0, 152.0], "value": 152.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 60.0, 63.0, 60.0, 60.0, 60.0, 60.0, 68.0], "value": 61.6, "unit": "score"}], "name": "kraken", "value": 1459.6, "alertThreshold": 2.0}]} 22:03:46 INFO - Return code: 0 22:03:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:03:46 INFO - # TBPL SUCCESS # 22:03:46 INFO - Running post-action listener: _resource_record_post_action 22:03:46 INFO - [mozharness: 2016-08-26 05:03:46.192384Z] Finished run-tests step (success) 22:03:46 INFO - Running post-run listener: _resource_record_post_run 22:03:46 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 324661248; Read time: 588; Write time: 556456 22:03:46 INFO - TinderboxPrint: CPU usage
13.0% 22:03:46 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 588 22:03:46 INFO - TinderboxPrint: I/O write bytes / time
324,661,248 / 556,456 22:03:46 INFO - TinderboxPrint: CPU idle
8,276.5 (87.0%) 22:03:46 INFO - TinderboxPrint: CPU user
1,183.9 (12.4%) 22:03:46 INFO - TinderboxPrint: Swap in / out
0 / 0 22:03:46 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 4096; Write bytes: 78573568; Read time: 368; Write time: 156988 22:03:46 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 246087680; Read time: 208; Write time: 399468 22:03:46 INFO - Running post-run listener: _upload_blobber_files 22:03:46 INFO - Blob upload gear active. 22:03:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:03:46 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 22:03:46 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'] 22:03:46 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 22:03:47 INFO - (blobuploader) - INFO - Open directory for files ... 22:03:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:03:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:48 INFO - (blobuploader) - INFO - Done attempting. 22:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:48 INFO - (blobuploader) - INFO - Done attempting. 22:03:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:03:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:03:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:03:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:03:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:03:52 INFO - (blobuploader) - INFO - Done attempting. 22:03:52 INFO - (blobuploader) - INFO - Iteration through files over. 22:03:52 INFO - Return code: 0 22:03:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:03:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:03:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39bfe8bf5868c3f81aae797621e9bba4d9632a46bcc7de7cd37e344ba5fef53e30aa340c2360e1b2a9a0d88a99d36a410cb5d7de96697168e34c86df23b182f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be52f7c2d7428c5bdbe3f814da0006cea5a9e78a20b84818e989cd428f06231b9a6e4f77d53b14fb9b4529d80ae0dbd83f2119c17f6e4ccd8a51a887bde3ede8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf7663b6197c45ed09419fd1f4439046b0fcaf56064b6c8d9dfeac5e36e111e07cfe90715ad639fa84ad0e70aa4fa663b29e0f7207b823383cc66b9875f4ff4"} 22:03:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:03:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:03:52 INFO - Contents: 22:03:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f39bfe8bf5868c3f81aae797621e9bba4d9632a46bcc7de7cd37e344ba5fef53e30aa340c2360e1b2a9a0d88a99d36a410cb5d7de96697168e34c86df23b182f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be52f7c2d7428c5bdbe3f814da0006cea5a9e78a20b84818e989cd428f06231b9a6e4f77d53b14fb9b4529d80ae0dbd83f2119c17f6e4ccd8a51a887bde3ede8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf7663b6197c45ed09419fd1f4439046b0fcaf56064b6c8d9dfeac5e36e111e07cfe90715ad639fa84ad0e70aa4fa663b29e0f7207b823383cc66b9875f4ff4"} 22:03:52 INFO - Running post-run listener: copy_logs_to_upload_dir 22:03:52 INFO - Copying logs to upload dir... 22:03:52 INFO - mkdir: /builds/slave/test/build/upload/logs 22:03:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.492733 ========= master_lag: 72.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 29 mins, 12 secs) (at 2016-08-25 22:05:04.599800) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 22:05:04.603470) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 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/f39bfe8bf5868c3f81aae797621e9bba4d9632a46bcc7de7cd37e344ba5fef53e30aa340c2360e1b2a9a0d88a99d36a410cb5d7de96697168e34c86df23b182f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be52f7c2d7428c5bdbe3f814da0006cea5a9e78a20b84818e989cd428f06231b9a6e4f77d53b14fb9b4529d80ae0dbd83f2119c17f6e4ccd8a51a887bde3ede8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf7663b6197c45ed09419fd1f4439046b0fcaf56064b6c8d9dfeac5e36e111e07cfe90715ad639fa84ad0e70aa4fa663b29e0f7207b823383cc66b9875f4ff4"} build_url:https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011437 build_url: 'https://queue.taskcluster.net/v1/task/EPg8_sQZT2GFZlbIwuwhVQ/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/f39bfe8bf5868c3f81aae797621e9bba4d9632a46bcc7de7cd37e344ba5fef53e30aa340c2360e1b2a9a0d88a99d36a410cb5d7de96697168e34c86df23b182f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be52f7c2d7428c5bdbe3f814da0006cea5a9e78a20b84818e989cd428f06231b9a6e4f77d53b14fb9b4529d80ae0dbd83f2119c17f6e4ccd8a51a887bde3ede8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf7663b6197c45ed09419fd1f4439046b0fcaf56064b6c8d9dfeac5e36e111e07cfe90715ad639fa84ad0e70aa4fa663b29e0f7207b823383cc66b9875f4ff4"}' ========= master_lag: 0.31 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 22:05:04.919925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-25 22:05:04.920271) ========= 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=5cf47e208febbcd81da23b2e0000026f-1472186078.448958-446611001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004578 ========= master_lag: 2.64 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-25 22:05:07.561535) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-08-25 22:05:07.561847) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-08-25 22:05:09.627824) ========= ========= Total master_lag: 75.33 =========