builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1472611727.51 results: success (0) buildid: 20160830170121 builduid: aa1b4338433b4c08a03768caedf130df revision: 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.509463) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.510762) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.511498) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.539360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.539679) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 19:48:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.0M=0s 2016-08-30 19:48:47 (29.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075323 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.714364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.714945) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015567 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.798384) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:47.798761) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e --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 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e --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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 19:48:48,161 truncating revision to first 12 chars 2016-08-30 19:48:48,161 Setting DEBUG logging. 2016-08-30 19:48:48,161 attempt 1/10 2016-08-30 19:48:48,161 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6b222a7b59be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 19:48:48,471 unpacking tar archive at: mozilla-aurora-6b222a7b59be/testing/mozharness/ program finished with exit code 0 elapsedTime=0.605905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:48.426176) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:48.426496) ========= script_repo_revision: 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:48.426941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:48.427250) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:48:48.438505) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-30 19:48:48.438871) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:48:48 INFO - MultiFileLogger online at 20160830 19:48:48 in /builds/slave/test 19:48:48 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 19:48:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:48:48 INFO - {'append_to_log': False, 19:48:48 INFO - 'base_work_dir': '/builds/slave/test', 19:48:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:48:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:48:48 INFO - 'branch': 'Mozilla-Aurora', 19:48:48 INFO - 'buildbot_json_path': 'buildprops.json', 19:48:48 INFO - 'config_files': ('talos/linux_config.py',), 19:48:48 INFO - 'default_actions': ('clobber', 19:48:48 INFO - 'read-buildbot-config', 19:48:48 INFO - 'download-and-extract', 19:48:48 INFO - 'populate-webroot', 19:48:48 INFO - 'create-virtualenv', 19:48:48 INFO - 'install', 19:48:48 INFO - 'run-tests'), 19:48:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:48:48 INFO - 'download_minidump_stackwalk': True, 19:48:48 INFO - 'download_symbols': 'ondemand', 19:48:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:48:48 INFO - 'tooltool.py': '/tools/tooltool.py', 19:48:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:48:48 INFO - '/tools/misc-python/virtualenv.py')}, 19:48:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:48:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:48:48 INFO - 'installer_path': 'installer.exe', 19:48:48 INFO - 'log_level': 'info', 19:48:48 INFO - 'log_name': 'talos', 19:48:48 INFO - 'log_to_console': True, 19:48:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:48:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:48:48 INFO - 'opt_config_files': (), 19:48:48 INFO - 'pip_index': False, 19:48:48 INFO - 'sps_profile': False, 19:48:48 INFO - 'sps_profile_interval': 0, 19:48:48 INFO - 'suite': 'dromaeojs', 19:48:48 INFO - 'system_bits': '32', 19:48:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:48:48 INFO - 'title': 'talos-linux64-ix-057', 19:48:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:48:48 INFO - 'use_talos_json': True, 19:48:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:48:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:48:48 INFO - 'work_dir': 'build'} 19:48:48 INFO - [mozharness: 2016-08-31 02:48:48.896418Z] Running clobber step. 19:48:48 INFO - Running pre-action listener: _resource_record_pre_action 19:48:48 INFO - Running main action method: clobber 19:48:48 INFO - rmtree: /builds/slave/test/build 19:48:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:48:49 INFO - Running post-action listener: _resource_record_post_action 19:48:49 INFO - [mozharness: 2016-08-31 02:48:49.879058Z] Finished clobber step (success) 19:48:49 INFO - [mozharness: 2016-08-31 02:48:49.879158Z] Running read-buildbot-config step. 19:48:49 INFO - Running pre-action listener: _resource_record_pre_action 19:48:49 INFO - Running main action method: read_buildbot_config 19:48:49 INFO - Using buildbot properties: 19:48:49 INFO - { 19:48:49 INFO - "project": "", 19:48:49 INFO - "product": "firefox", 19:48:49 INFO - "script_repo_revision": "production", 19:48:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:48:49 INFO - "repository": "", 19:48:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:48:49 INFO - "buildid": "20160830170121", 19:48:49 INFO - "pgo_build": "True", 19:48:49 INFO - "basedir": "/builds/slave/test", 19:48:49 INFO - "buildnumber": 710, 19:48:49 INFO - "slavename": "talos-linux64-ix-057", 19:48:49 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:48:49 INFO - "platform": "ubuntu64_hw", 19:48:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:48:49 INFO - "branch": "mozilla-aurora", 19:48:49 INFO - "repo_path": "releases/mozilla-aurora", 19:48:49 INFO - "revision": "6b222a7b59becaa25006567dc1b9c4a5c67b7e4e", 19:48:49 INFO - "stage_platform": "linux64-pgo", 19:48:49 INFO - "builduid": "aa1b4338433b4c08a03768caedf130df", 19:48:49 INFO - "slavebuilddir": "test" 19:48:49 INFO - } 19:48:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 19:48:49 INFO - Running post-action listener: _resource_record_post_action 19:48:49 INFO - [mozharness: 2016-08-31 02:48:49.881572Z] Finished read-buildbot-config step (success) 19:48:49 INFO - [mozharness: 2016-08-31 02:48:49.881661Z] Running download-and-extract step. 19:48:49 INFO - Running pre-action listener: _resource_record_pre_action 19:48:49 INFO - Running main action method: download_and_extract 19:48:49 INFO - mkdir: /builds/slave/test/build/tests 19:48: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')]} 19:48:49 INFO - https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:48:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:48:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:48:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:48:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:48:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:48:49 INFO - retry: Failed, sleeping 30 seconds before retrying 19:49:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:49:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:49:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:49:19 INFO - retry: Failed, sleeping 60 seconds before retrying 19:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:50:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:50:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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! 19:50:19 INFO - Caught exception: HTTP Error 404: Not Found 19:50:19 INFO - Caught exception: HTTP Error 404: Not Found 19:50:19 INFO - Caught exception: HTTP Error 404: Not Found 19:50:19 INFO - trying https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:50:19 INFO - Downloading https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:20 INFO - Downloaded 1361 bytes. 19:50:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:50:20 INFO - Using the following test package requirements: 19:50:20 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:50:20 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:50:20 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:50:20 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'jsshell-linux-x86_64.zip'], 19:50:20 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:50:20 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:50:20 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:50:20 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:50:20 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:50:20 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:50:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:50:20 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 19:50:20 INFO - https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:50:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:50:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:50:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:50:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:50:20 INFO - retry: Failed, sleeping 30 seconds before retrying 19:50:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:50:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:50:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:50:50 INFO - retry: Failed, sleeping 60 seconds before retrying 19:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:51:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:51:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:51:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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! 19:51:50 INFO - Caught exception: HTTP Error 404: Not Found 19:51:50 INFO - Caught exception: HTTP Error 404: Not Found 19:51:50 INFO - Caught exception: HTTP Error 404: Not Found 19:51:50 INFO - trying https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:51:50 INFO - Downloading https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:51:51 INFO - Downloaded 21770091 bytes. 19:51:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:51:52 INFO - Return code: 0 19:51:52 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 19:51: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')]} 19:51:52 INFO - https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:51:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:51:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:51:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:51:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:51:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:51:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:51:52 INFO - retry: Failed, sleeping 30 seconds before retrying 19:52:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:52:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:52:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:52:22 INFO - retry: Failed, sleeping 60 seconds before retrying 19:53:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:53:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:53:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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! 19:53:22 INFO - Caught exception: HTTP Error 404: Not Found 19:53:22 INFO - Caught exception: HTTP Error 404: Not Found 19:53:22 INFO - Caught exception: HTTP Error 404: Not Found 19:53:22 INFO - trying https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:53:22 INFO - Downloading https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:23 INFO - Downloaded 21770091 bytes. 19:53:23 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'] 19:53:23 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 19:53:24 INFO - Return code: 0 19:53:24 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')]} 19:53:24 INFO - https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:53:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:53:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:53:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:53:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:53:24 INFO - retry: Failed, sleeping 30 seconds before retrying 19:53:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:53:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:53:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:53:55 INFO - retry: Failed, sleeping 60 seconds before retrying 19:54:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:54:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:54:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:54:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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! 19:54:55 INFO - Caught exception: HTTP Error 404: Not Found 19:54:55 INFO - Caught exception: HTTP Error 404: Not Found 19:54:55 INFO - Caught exception: HTTP Error 404: Not Found 19:54:55 INFO - trying https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:54:55 INFO - Downloading https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:54:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:54:55 INFO - Downloaded 14413740 bytes. 19:54:55 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'] 19:54:55 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 19:54:56 INFO - Return code: 0 19:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:54:56 INFO - https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:54:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:54:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:54:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:54:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:54:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:54:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:54:56 INFO - retry: Failed, sleeping 30 seconds before retrying 19:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:55:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:55:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:55:26 INFO - retry: Failed, sleeping 60 seconds before retrying 19:56:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:56:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:56:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:56:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XOKJgaiATNS6TZZa7xOkug/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! 19:56:26 INFO - Caught exception: HTTP Error 404: Not Found 19:56:26 INFO - Caught exception: HTTP Error 404: Not Found 19:56:26 INFO - Caught exception: HTTP Error 404: Not Found 19:56:26 INFO - trying https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:56:26 INFO - Downloading https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:56:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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 19:56:27 INFO - Downloaded 63871846 bytes. 19:56:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:56:27 INFO - mkdir: /builds/slave/test/properties 19:56:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:56:27 INFO - Writing to file /builds/slave/test/properties/build_url 19:56:27 INFO - Contents: 19:56:27 INFO - build_url:https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:56:28 INFO - Running post-action listener: _resource_record_post_action 19:56:28 INFO - Running post-action listener: set_extra_try_arguments 19:56:28 INFO - [mozharness: 2016-08-31 02:56:28.089998Z] Finished download-and-extract step (success) 19:56:28 INFO - [mozharness: 2016-08-31 02:56:28.090231Z] Running populate-webroot step. 19:56:28 INFO - Running pre-action listener: _resource_record_pre_action 19:56:28 INFO - Running main action method: populate_webroot 19:56:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:56:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:56:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:56:28 INFO - '--tppagecycles', 19:56:28 INFO - '7'], 19:56:28 INFO - 'tests': ['tsvgm']}}, 19:56:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:56:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:56:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:56:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:56:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:56:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['damp', 'tps']}, 19:56:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'tests': ['damp', 'tps']}, 19:56:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['dromaeo_dom']}, 19:56:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:56:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['basic_compositor_video']}, 19:56:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:56:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['a11yr', 19:56:28 INFO - 'ts_paint', 19:56:28 INFO - 'tpaint', 19:56:28 INFO - 'sessionrestore', 19:56:28 INFO - 'sessionrestore_no_auto_restore', 19:56:28 INFO - 'tabpaint']}, 19:56:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['tsvgx', 19:56:28 INFO - 'tsvgr_opacity', 19:56:28 INFO - 'tart', 19:56:28 INFO - 'tscrollx', 19:56:28 INFO - 'cart']}, 19:56:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:56:28 INFO - 'tsvgr_opacity', 19:56:28 INFO - 'tart', 19:56:28 INFO - 'tscrollx', 19:56:28 INFO - 'cart']}, 19:56:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'talos_options': ['--disable-e10s'], 19:56:28 INFO - 'tests': ['tp5o']}, 19:56:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'tests': ['tp5o']}, 19:56:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'talos_options': ['--disable-e10s', 19:56:28 INFO - '--xperf_path', 19:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:28 INFO - 'tests': ['tp5n']}, 19:56:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:56:28 INFO - 'talos_options': ['--xperf_path', 19:56:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:56:28 INFO - 'tests': ['tp5n']}}, 19:56:28 INFO - 'talos.zip': {'path': '', 19:56:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:56:28 INFO - Running post-action listener: _resource_record_post_action 19:56:28 INFO - [mozharness: 2016-08-31 02:56:28.108401Z] Finished populate-webroot step (success) 19:56:28 INFO - [mozharness: 2016-08-31 02:56:28.108490Z] Running create-virtualenv step. 19:56:28 INFO - Running pre-action listener: _resource_record_pre_action 19:56:28 INFO - Running main action method: create_virtualenv 19:56:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:56:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:56:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:56:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:56:28 INFO - Using real prefix '/usr' 19:56:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:56:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:56:30 INFO - Installing pip.................done. 19:56:30 INFO - Return code: 0 19:56:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:56:30 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')]} 19:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:30 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')]} 19:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:30 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 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:30 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 19:56:30 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 19:56:30 INFO - Using env: {'DISPLAY': ':0', 19:56:30 INFO - 'HOME': '/home/cltbld', 19:56:30 INFO - 'LANG': 'en_US.UTF-8', 19:56:30 INFO - 'LANGUAGE': 'en_US:en', 19:56:30 INFO - 'LOGNAME': 'cltbld', 19:56:30 INFO - 'MAIL': '/var/mail/cltbld', 19:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:30 INFO - 'MOZ_NO_REMOTE': '1', 19:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:30 INFO - 'NO_EM_RESTART': '1', 19:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:30 INFO - 'PWD': '/builds/slave/test', 19:56:30 INFO - 'SHELL': '/bin/bash', 19:56:30 INFO - 'SHLVL': '1', 19:56:30 INFO - 'TERM': 'linux', 19:56:30 INFO - 'TMOUT': '86400', 19:56:30 INFO - 'USER': 'cltbld', 19:56:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:30 INFO - '_': '/tools/buildbot/bin/python'} 19:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:30 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 19:56:30 INFO - Cleaning up... 19:56:30 INFO - Return code: 0 19:56:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:56:30 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')]} 19:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:30 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')]} 19:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:30 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 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:30 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 19:56:30 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 19:56:30 INFO - Using env: {'DISPLAY': ':0', 19:56:30 INFO - 'HOME': '/home/cltbld', 19:56:30 INFO - 'LANG': 'en_US.UTF-8', 19:56:30 INFO - 'LANGUAGE': 'en_US:en', 19:56:30 INFO - 'LOGNAME': 'cltbld', 19:56:30 INFO - 'MAIL': '/var/mail/cltbld', 19:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:30 INFO - 'MOZ_NO_REMOTE': '1', 19:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:30 INFO - 'NO_EM_RESTART': '1', 19:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:30 INFO - 'PWD': '/builds/slave/test', 19:56:30 INFO - 'SHELL': '/bin/bash', 19:56:30 INFO - 'SHLVL': '1', 19:56:30 INFO - 'TERM': 'linux', 19:56:30 INFO - 'TMOUT': '86400', 19:56:30 INFO - 'USER': 'cltbld', 19:56:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:30 INFO - '_': '/tools/buildbot/bin/python'} 19:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:31 INFO - Downloading/unpacking psutil>=3.1.1 19:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:56:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:56:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:56:34 INFO - Installing collected packages: psutil 19:56:34 INFO - Running setup.py install for psutil 19:56:34 INFO - building 'psutil._psutil_linux' extension 19:56:34 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 19:56:34 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 19:56:34 INFO - building 'psutil._psutil_posix' extension 19:56:34 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 19:56:34 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 19:56:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:56:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:56:34 INFO - Successfully installed psutil 19:56:34 INFO - Cleaning up... 19:56:34 INFO - Return code: 0 19:56:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:56:34 INFO - Using env: {'DISPLAY': ':0', 19:56:34 INFO - 'HOME': '/home/cltbld', 19:56:34 INFO - 'LANG': 'en_US.UTF-8', 19:56:34 INFO - 'LANGUAGE': 'en_US:en', 19:56:34 INFO - 'LOGNAME': 'cltbld', 19:56:34 INFO - 'MAIL': '/var/mail/cltbld', 19:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:34 INFO - 'MOZ_NO_REMOTE': '1', 19:56:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:34 INFO - 'NO_EM_RESTART': '1', 19:56:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:34 INFO - 'PWD': '/builds/slave/test', 19:56:34 INFO - 'SHELL': '/bin/bash', 19:56:34 INFO - 'SHLVL': '1', 19:56:34 INFO - 'TERM': 'linux', 19:56:34 INFO - 'TMOUT': '86400', 19:56:34 INFO - 'USER': 'cltbld', 19:56:34 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:34 INFO - '_': '/tools/buildbot/bin/python'} 19:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:56:36 INFO - Installing collected packages: mozsystemmonitor 19:56:36 INFO - Running setup.py install for mozsystemmonitor 19:56:36 INFO - Successfully installed mozsystemmonitor 19:56:36 INFO - Cleaning up... 19:56:36 INFO - Return code: 0 19:56:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:56:36 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')]} 19:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:36 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')]} 19:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:36 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 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:36 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 19:56:36 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 19:56:36 INFO - Using env: {'DISPLAY': ':0', 19:56:36 INFO - 'HOME': '/home/cltbld', 19:56:36 INFO - 'LANG': 'en_US.UTF-8', 19:56:36 INFO - 'LANGUAGE': 'en_US:en', 19:56:36 INFO - 'LOGNAME': 'cltbld', 19:56:36 INFO - 'MAIL': '/var/mail/cltbld', 19:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:36 INFO - 'MOZ_NO_REMOTE': '1', 19:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:36 INFO - 'NO_EM_RESTART': '1', 19:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:36 INFO - 'PWD': '/builds/slave/test', 19:56:36 INFO - 'SHELL': '/bin/bash', 19:56:36 INFO - 'SHLVL': '1', 19:56:36 INFO - 'TERM': 'linux', 19:56:36 INFO - 'TMOUT': '86400', 19:56:36 INFO - 'USER': 'cltbld', 19:56:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:36 INFO - '_': '/tools/buildbot/bin/python'} 19:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:37 INFO - Downloading/unpacking jsonschema==2.5.1 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:56:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:39 INFO - Downloading functools32-3.2.3-2.tar.gz 19:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:56:39 INFO - warning: no files found matching '*.txt' 19:56:39 INFO - no previously-included directories found matching 'build' 19:56:39 INFO - no previously-included directories found matching 'dist' 19:56:39 INFO - no previously-included directories found matching '.git*' 19:56:39 INFO - Installing collected packages: jsonschema, functools32 19:56:39 INFO - Running setup.py install for functools32 19:56:39 INFO - warning: no files found matching '*.txt' 19:56:39 INFO - no previously-included directories found matching 'build' 19:56:39 INFO - no previously-included directories found matching 'dist' 19:56:39 INFO - no previously-included directories found matching '.git*' 19:56:39 INFO - Successfully installed jsonschema functools32 19:56:39 INFO - Cleaning up... 19:56:39 INFO - Return code: 0 19:56:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:56:39 INFO - Using env: {'DISPLAY': ':0', 19:56:39 INFO - 'HOME': '/home/cltbld', 19:56:39 INFO - 'LANG': 'en_US.UTF-8', 19:56:39 INFO - 'LANGUAGE': 'en_US:en', 19:56:39 INFO - 'LOGNAME': 'cltbld', 19:56:39 INFO - 'MAIL': '/var/mail/cltbld', 19:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:39 INFO - 'MOZ_NO_REMOTE': '1', 19:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:39 INFO - 'NO_EM_RESTART': '1', 19:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:39 INFO - 'PWD': '/builds/slave/test', 19:56:39 INFO - 'SHELL': '/bin/bash', 19:56:39 INFO - 'SHLVL': '1', 19:56:39 INFO - 'TERM': 'linux', 19:56:39 INFO - 'TMOUT': '86400', 19:56:39 INFO - 'USER': 'cltbld', 19:56:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:39 INFO - '_': '/tools/buildbot/bin/python'} 19:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:56:39 INFO - Cleaning up... 19:56:39 INFO - Return code: 0 19:56:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:56: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')]} 19:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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')]} 19:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56: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', '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 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:56: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 blobuploader==1.2.4 19:56:39 INFO - Using env: {'DISPLAY': ':0', 19:56:39 INFO - 'HOME': '/home/cltbld', 19:56:39 INFO - 'LANG': 'en_US.UTF-8', 19:56:39 INFO - 'LANGUAGE': 'en_US:en', 19:56:39 INFO - 'LOGNAME': 'cltbld', 19:56:39 INFO - 'MAIL': '/var/mail/cltbld', 19:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:39 INFO - 'MOZ_NO_REMOTE': '1', 19:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:39 INFO - 'NO_EM_RESTART': '1', 19:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:39 INFO - 'PWD': '/builds/slave/test', 19:56:39 INFO - 'SHELL': '/bin/bash', 19:56:39 INFO - 'SHLVL': '1', 19:56:39 INFO - 'TERM': 'linux', 19:56:39 INFO - 'TMOUT': '86400', 19:56:39 INFO - 'USER': 'cltbld', 19:56:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:39 INFO - '_': '/tools/buildbot/bin/python'} 19:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:39 INFO - Downloading/unpacking blobuploader==1.2.4 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:56:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:56:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:56:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56: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 19:56:42 INFO - Downloading docopt-0.6.1.tar.gz 19:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:56:42 INFO - Installing collected packages: blobuploader, requests, docopt 19:56:42 INFO - Running setup.py install for blobuploader 19:56:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:56:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:56:42 INFO - Running setup.py install for requests 19:56:43 INFO - Running setup.py install for docopt 19:56:43 INFO - Successfully installed blobuploader requests docopt 19:56:43 INFO - Cleaning up... 19:56:43 INFO - Return code: 0 19:56:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:56:43 INFO - Using env: {'DISPLAY': ':0', 19:56:43 INFO - 'HOME': '/home/cltbld', 19:56:43 INFO - 'LANG': 'en_US.UTF-8', 19:56:43 INFO - 'LANGUAGE': 'en_US:en', 19:56:43 INFO - 'LOGNAME': 'cltbld', 19:56:43 INFO - 'MAIL': '/var/mail/cltbld', 19:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:43 INFO - 'MOZ_NO_REMOTE': '1', 19:56:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:43 INFO - 'NO_EM_RESTART': '1', 19:56:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:43 INFO - 'PWD': '/builds/slave/test', 19:56:43 INFO - 'SHELL': '/bin/bash', 19:56:43 INFO - 'SHLVL': '1', 19:56:43 INFO - 'TERM': 'linux', 19:56:43 INFO - 'TMOUT': '86400', 19:56:43 INFO - 'USER': 'cltbld', 19:56:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:43 INFO - '_': '/tools/buildbot/bin/python'} 19:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:43 INFO - Running setup.py (path:/tmp/pip-AGSGka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:43 INFO - Running setup.py (path:/tmp/pip-_DMAsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:43 INFO - Running setup.py (path:/tmp/pip-pAEDL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:43 INFO - Running setup.py (path:/tmp/pip-SX5kPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:43 INFO - Running setup.py (path:/tmp/pip-Qj1HB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:43 INFO - Running setup.py (path:/tmp/pip-4c4yxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:43 INFO - Running setup.py (path:/tmp/pip-uWTsVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:43 INFO - Running setup.py (path:/tmp/pip-W6C_lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:44 INFO - Running setup.py (path:/tmp/pip-gFabp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:44 INFO - Running setup.py (path:/tmp/pip-yb4fi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:44 INFO - Running setup.py (path:/tmp/pip-RRvA7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:44 INFO - Running setup.py (path:/tmp/pip-LsH8lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:44 INFO - Running setup.py (path:/tmp/pip-BAC45p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:44 INFO - Running setup.py (path:/tmp/pip-ki36ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:44 INFO - Running setup.py (path:/tmp/pip-pZMuLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:44 INFO - Running setup.py (path:/tmp/pip-WtEpqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:44 INFO - Running setup.py (path:/tmp/pip-JNO19b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:56:44 INFO - Running setup.py install for manifestparser 19:56:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:56:44 INFO - Running setup.py install for mozcrash 19:56:44 INFO - Running setup.py install for mozdebug 19:56:44 INFO - Running setup.py install for mozdevice 19:56:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for mozfile 19:56:45 INFO - Running setup.py install for mozhttpd 19:56:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for mozinfo 19:56:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for mozInstall 19:56:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for mozleak 19:56:45 INFO - Running setup.py install for mozlog 19:56:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for moznetwork 19:56:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:56:45 INFO - Running setup.py install for mozprocess 19:56:45 INFO - Running setup.py install for mozprofile 19:56:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:56:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:56:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:56:46 INFO - Running setup.py install for mozrunner 19:56:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:56:46 INFO - Running setup.py install for mozscreenshot 19:56:46 INFO - Running setup.py install for moztest 19:56:46 INFO - Running setup.py install for mozversion 19:56:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:56:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:56:46 INFO - Cleaning up... 19:56:46 INFO - Return code: 0 19:56:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:56:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:56:46 INFO - Using env: {'DISPLAY': ':0', 19:56:46 INFO - 'HOME': '/home/cltbld', 19:56:46 INFO - 'LANG': 'en_US.UTF-8', 19:56:46 INFO - 'LANGUAGE': 'en_US:en', 19:56:46 INFO - 'LOGNAME': 'cltbld', 19:56:46 INFO - 'MAIL': '/var/mail/cltbld', 19:56:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:46 INFO - 'MOZ_NO_REMOTE': '1', 19:56:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:46 INFO - 'NO_EM_RESTART': '1', 19:56:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:46 INFO - 'PWD': '/builds/slave/test', 19:56:46 INFO - 'SHELL': '/bin/bash', 19:56:46 INFO - 'SHLVL': '1', 19:56:46 INFO - 'TERM': 'linux', 19:56:46 INFO - 'TMOUT': '86400', 19:56:46 INFO - 'USER': 'cltbld', 19:56:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:46 INFO - '_': '/tools/buildbot/bin/python'} 19:56:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:46 INFO - Running setup.py (path:/tmp/pip-VwYX6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:46 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)) 19:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:46 INFO - Running setup.py (path:/tmp/pip-qeecNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:46 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)) 19:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:46 INFO - Running setup.py (path:/tmp/pip-Xc3cOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:46 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)) 19:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:46 INFO - Running setup.py (path:/tmp/pip-Mt3vjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:46 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)) 19:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:46 INFO - Running setup.py (path:/tmp/pip-xZ2UT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:47 INFO - Running setup.py (path:/tmp/pip-1JwtrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:47 INFO - Running setup.py (path:/tmp/pip-p3fj7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:47 INFO - Running setup.py (path:/tmp/pip-6kkxmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:47 INFO - Running setup.py (path:/tmp/pip-opIhCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:47 INFO - Running setup.py (path:/tmp/pip-UCa8Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:47 INFO - Running setup.py (path:/tmp/pip-s47Wyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:47 INFO - Running setup.py (path:/tmp/pip-bAgxMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:47 INFO - Running setup.py (path:/tmp/pip-zQtcHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:47 INFO - Running setup.py (path:/tmp/pip-mPuKEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:47 INFO - Running setup.py (path:/tmp/pip-46MHTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:47 INFO - Running setup.py (path:/tmp/pip-O32nei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:47 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)) 19:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:47 INFO - Running setup.py (path:/tmp/pip-ReKntJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:47 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)) 19:56:47 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)) 19:56:47 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)) 19:56:47 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)) 19:56:47 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)) 19:56:47 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)) 19:56:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:49 INFO - Downloading blessings-1.6.tar.gz 19:56:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:56:49 INFO - Installing collected packages: blessings 19:56:49 INFO - Running setup.py install for blessings 19:56:50 INFO - Successfully installed blessings 19:56:50 INFO - Cleaning up... 19:56:50 INFO - Return code: 0 19:56:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:50 INFO - Reading from file tmpfile_stdout 19:56:50 INFO - Current package versions: 19:56:50 INFO - argparse == 1.2.1 19:56:50 INFO - blessings == 1.6 19:56:50 INFO - blobuploader == 1.2.4 19:56:50 INFO - docopt == 0.6.1 19:56:50 INFO - functools32 == 3.2.3-2 19:56:50 INFO - jsonschema == 2.5.1 19:56:50 INFO - manifestparser == 1.1 19:56:50 INFO - mozInstall == 1.12 19:56:50 INFO - mozcrash == 0.17 19:56:50 INFO - mozdebug == 0.1 19:56:50 INFO - mozdevice == 0.48 19:56:50 INFO - mozfile == 1.2 19:56:50 INFO - mozhttpd == 0.7 19:56:50 INFO - mozinfo == 0.9 19:56:50 INFO - mozleak == 0.1 19:56:50 INFO - mozlog == 3.3 19:56:50 INFO - moznetwork == 0.27 19:56:50 INFO - mozprocess == 0.23 19:56:50 INFO - mozprofile == 0.28 19:56:50 INFO - mozrunner == 6.12 19:56:50 INFO - mozscreenshot == 0.1 19:56:50 INFO - mozsystemmonitor == 0.3 19:56:50 INFO - moztest == 0.7 19:56:50 INFO - mozversion == 1.4 19:56:50 INFO - psutil == 3.1.1 19:56:50 INFO - requests == 1.2.3 19:56:50 INFO - wsgiref == 0.1.2 19:56:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:56:50 INFO - Using env: {'DISPLAY': ':0', 19:56:50 INFO - 'HOME': '/home/cltbld', 19:56:50 INFO - 'LANG': 'en_US.UTF-8', 19:56:50 INFO - 'LANGUAGE': 'en_US:en', 19:56:50 INFO - 'LOGNAME': 'cltbld', 19:56:50 INFO - 'MAIL': '/var/mail/cltbld', 19:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:50 INFO - 'MOZ_NO_REMOTE': '1', 19:56:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:50 INFO - 'NO_EM_RESTART': '1', 19:56:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:50 INFO - 'PWD': '/builds/slave/test', 19:56:50 INFO - 'SHELL': '/bin/bash', 19:56:50 INFO - 'SHLVL': '1', 19:56:50 INFO - 'TERM': 'linux', 19:56:50 INFO - 'TMOUT': '86400', 19:56:50 INFO - 'USER': 'cltbld', 19:56:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:50 INFO - '_': '/tools/buildbot/bin/python'} 19:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 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)) 19:56:50 INFO - Cleaning up... 19:56:50 INFO - Return code: 0 19:56:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:56:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:56:50 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 0x26d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, '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': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', '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 19:56:50 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 19:56:50 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 19:56:50 INFO - Using env: {'DISPLAY': ':0', 19:56:50 INFO - 'HOME': '/home/cltbld', 19:56:50 INFO - 'LANG': 'en_US.UTF-8', 19:56:50 INFO - 'LANGUAGE': 'en_US:en', 19:56:50 INFO - 'LOGNAME': 'cltbld', 19:56:50 INFO - 'MAIL': '/var/mail/cltbld', 19:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:50 INFO - 'MOZ_NO_REMOTE': '1', 19:56:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:56:50 INFO - 'NO_EM_RESTART': '1', 19:56:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:50 INFO - 'PWD': '/builds/slave/test', 19:56:50 INFO - 'SHELL': '/bin/bash', 19:56:50 INFO - 'SHLVL': '1', 19:56:50 INFO - 'TERM': 'linux', 19:56:50 INFO - 'TMOUT': '86400', 19:56:50 INFO - 'USER': 'cltbld', 19:56:50 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:50 INFO - '_': '/tools/buildbot/bin/python'} 19:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:56:50 INFO - Cleaning up... 19:56:50 INFO - Return code: 0 19:56:50 INFO - Running post-action listener: _resource_record_post_action 19:56:50 INFO - Running post-action listener: _start_resource_monitoring 19:56:50 INFO - Starting resource monitoring. 19:56:50 INFO - [mozharness: 2016-08-31 02:56:50.780961Z] Finished create-virtualenv step (success) 19:56:50 INFO - [mozharness: 2016-08-31 02:56:50.781433Z] Running install step. 19:56:50 INFO - Running pre-action listener: _resource_record_pre_action 19:56:50 INFO - Running main action method: install 19:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:50 INFO - Reading from file tmpfile_stdout 19:56:50 INFO - Detecting whether we're running mozinstall >=1.0... 19:56:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:56:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:56:51 INFO - Reading from file tmpfile_stdout 19:56:51 INFO - Output received: 19:56:51 INFO - Usage: mozinstall [options] installer 19:56:51 INFO - Options: 19:56:51 INFO - -h, --help show this help message and exit 19:56:51 INFO - -d DEST, --destination=DEST 19:56:51 INFO - Directory to install application into. [default: 19:56:51 INFO - "/builds/slave/test"] 19:56:51 INFO - --app=APP Application being installed. [default: firefox] 19:56:51 INFO - mkdir: /builds/slave/test/build/application 19:56:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:57:07 INFO - Reading from file tmpfile_stdout 19:57:07 INFO - Output received: 19:57:07 INFO - /builds/slave/test/build/application/firefox/firefox 19:57:07 INFO - Running post-action listener: _resource_record_post_action 19:57:07 INFO - [mozharness: 2016-08-31 02:57:07.713259Z] Finished install step (success) 19:57:07 INFO - [mozharness: 2016-08-31 02:57:07.713537Z] Running run-tests step. 19:57:07 INFO - Running pre-action listener: _resource_record_pre_action 19:57:07 INFO - Running main action method: run_tests 19:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:57:07 INFO - Python 2.7.3 19:57:07 INFO - Return code: 0 19:57:07 INFO - grabbing minidump binary from tooltool 19:57:07 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')]} 19:57:07 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 0x29953b0>, '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 0x29962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2996740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:57:07 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 19:57:07 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 19:57:07 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 19:57:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:57:07 INFO - Return code: 0 19:57:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:57:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:57:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:57:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:57:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:57:07 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/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 19:57:07 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/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --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 19:57:07 INFO - Using env: {'DISPLAY': ':0', 19:57:07 INFO - 'HOME': '/home/cltbld', 19:57:07 INFO - 'LANG': 'en_US.UTF-8', 19:57:07 INFO - 'LANGUAGE': 'en_US:en', 19:57:07 INFO - 'LOGNAME': 'cltbld', 19:57:07 INFO - 'MAIL': '/var/mail/cltbld', 19:57:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:57:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:57:07 INFO - 'MOZ_NO_REMOTE': '1', 19:57:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:57:07 INFO - 'NO_EM_RESTART': '1', 19:57:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:57:07 INFO - 'PWD': '/builds/slave/test', 19:57:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:57:07 INFO - 'SHELL': '/bin/bash', 19:57:07 INFO - 'SHLVL': '1', 19:57:07 INFO - 'TERM': 'linux', 19:57:07 INFO - 'TMOUT': '86400', 19:57:07 INFO - 'USER': 'cltbld', 19:57:07 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581', 19:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:57:07 INFO - '_': '/tools/buildbot/bin/python'} 19:57:07 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/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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 19:57:07 INFO - mozversion application_buildid: 20160830170121 19:57:07 INFO - mozversion application_changeset: 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e 19:57:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:57:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:57:07 INFO - mozversion application_name: Firefox 19:57:07 INFO - mozversion application_remotingname: firefox-dev 19:57:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:57:07 INFO - mozversion application_vendor: Mozilla 19:57:07 INFO - mozversion application_version: 50.0a2 19:57:07 INFO - mozversion platform_buildid: 20160830170121 19:57:07 INFO - mozversion platform_changeset: 6b222a7b59becaa25006567dc1b9c4a5c67b7e4e 19:57:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:57:07 INFO - mozversion platform_version: 50.0a2 19:57:07 INFO - using testdate: 1472612227 19:57:07 INFO - actual date: 1472612227 19:57:07 INFO - starting webserver on 'localhost:33504' 19:57:07 INFO - SUITE-START | Running 2 tests 19:57:07 INFO - TEST-START | dromaeo_css 19:57:07 INFO - operating with platform_type : linux_ 19:57:07 INFO - Initialising browser for dromaeo_css test... 19:57:07 INFO - TEST-INFO | started process 21410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps799pR/profile http://localhost:33504/getInfo.html) 19:57:12 INFO - PROCESS | 21410 | __metrics Screen width/height:1600/1200 19:57:12 INFO - PROCESS | 21410 | colorDepth:24 19:57:12 INFO - PROCESS | 21410 | Browser inner width/height: 1024/697 19:57:12 INFO - PROCESS | 21410 | __metrics 19:57:13 INFO - PROCESS | 21410 | 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" 19:57:13 INFO - PROCESS | 21410 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:57:13 INFO - PROCESS | 21410 | console.error: PushService: 19:57:13 INFO - PROCESS | 21410 | stateChangeProcessEnqueue: Error transitioning state 19:57:13 INFO - PROCESS | 21410 | UnknownError 19:57:14 INFO - TEST-INFO | 21410: exit 0 19:57:14 INFO - Browser initialized. 19:57:14 INFO - Running cycle 1/1 for dromaeo_css test... 19:57:14 INFO - TEST-INFO | started process 21498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps799pR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:57:25 INFO - PROCESS | 21498 | RSS: Main: 145457152 19:57:25 INFO - PROCESS | 21498 | 19:58:16 INFO - PROCESS | 21498 | 1472612296589 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] 20:00:25 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33504/tests/dromaeo/cssquery-ext.html) 20:00:26 INFO - PROCESS | 21498 | RSS: Main: 224780288 20:00:26 INFO - PROCESS | 21498 | 20:03:29 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-ext.html (next: http://localhost:33504/tests/dromaeo/cssquery-jquery.html) 20:03:29 INFO - PROCESS | 21498 | RSS: Main: 228458496 20:03:29 INFO - PROCESS | 21498 | 20:06:31 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33504/tests/dromaeo/cssquery-mootools.html) 20:06:32 INFO - PROCESS | 21498 | RSS: Main: 175296512 20:06:32 INFO - PROCESS | 21498 | 20:09:34 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33504/tests/dromaeo/cssquery-prototype.html) 20:09:34 INFO - PROCESS | 21498 | RSS: Main: 176525312 20:09:34 INFO - PROCESS | 21498 | 20:12:37 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33504/tests/dromaeo/cssquery-yui.html) 20:12:37 INFO - PROCESS | 21498 | RSS: Main: 176893952 20:12:37 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | Cycle 1(1): loaded http://localhost:33504/tests/dromaeo/cssquery-yui.html (next: http://localhost:33504/tests/dromaeo/cssquery-dojo.html) 20:15:40 INFO - PROCESS | 21498 | RSS: Main: 265838592 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | __start_tp_report 20:15:40 INFO - PROCESS | 21498 | _x_x_mozilla_page_load 20:15:40 INFO - PROCESS | 21498 | _x_x_mozilla_page_load_details 20:15:40 INFO - PROCESS | 21498 | |i|pagename|runs| 20:15:40 INFO - PROCESS | 21498 | |0;dojo.html;3135.576697145886;3164.493681011039;3178.046586024192;3184.012955983645;3213.38945600336;5541.085720856059;5631.0708733059;5650.6892120933335;5651.2935883014625;5674;5569.916451253234;5674.375818659944;5676.545876329894;5680.460356266159;5717.2281741964825;5033.295338652573;5091.261767043777;5107.897842043166;5136.512031357015;5201.40183878855;5158.664686795365;5228.73856307185;5234.398044224906;5235.502627250406;5239.895202095956;5046.268291097788;5095.261187127864;5101.744912754366;5109.437961824215;5120.820771273006;5198.766055527511;5203.323567936182;5219.321488206547;5230.217261311552;5261;4927.039227350668;4976.4277108132665;5020.221865610836;5127.794888204505;5133.409657889353;5498.092814685612;5524.309461317335;5545.889082218373;5557.749901254453;5588.357338905997;6044.184035155242;6053.848653783647;6060.060690592965;6074.526036179504;6084.421979911901;5816.040353341694;5850.239468869062;5866.97327967604;5964.791232306892;6078.544109191828;5553.055980483327;5578.386377498472;5584.301962254718;5596.27248457706;5621.409751976024;6037.607555508878;6114.327423983358;6124.908126378108;6126.991097449049;6129.828674031736;5842.766289348378;5880;5885.058390657474;5979.222701048923;5985.820425387245;6025.126356678345;6046.153538504613;6055.333913269536;6059.787907423307;6064.818055458343;5795.536357091422;6022.789202377896;6024.759009639653;6025.307089684655;6028.216331876828;5918.378570250062;5989.610675306091;6023.6385816851125;6129.328227221949;6142.498775697809;4609.377734005933;4617.376654151646;4624.537546245416;4635.582797548237;4656.627469802408;4045.514538255369;4092.1611069730634;4107.363358679409;4113.01287690952;4122.031322639177;5724.484796368347;5732.082866741384;5733.140028995684;5827.737751801179;5862.032764593794;6111.672398433294;6124.877502449926;6139.171211886338;6142.692865356715;6143.754249830046;5514.80698175562;5817.7963771267805;5824.0390335595;5829.912551311733;5832.9708351457975;5069.8732531687;5135.717535535581;5139.408967968731;5175.974120129451;5207.921881171786;6348.746050158034;6351.17434733491;6358.77744278948;6359.395857393541;6366.649834259068;5735.483806457439;6170.629762214281;6178.443940045418;6179.3820617937845;6179.969100154651;6084.60450070744;6124.816255512342;6129.049997250433;6154.507639388928;6196.566240363131;5876.41235876409;5917.733701983507;5925.585209035326;5927.970360148344;5974.551908606785;5797.884042319214;6040.6979651018155;6050.9394906050375;6053.273607167168;6066.757329706938;5679.8864022720145;5820.185174075548;5865.208196893364;5921.259842519685;5941.019731744386;5860.765569377178;5875.059990401686;5881.9705901470215;5888.263967004124;5890.440408161217;5544.085225937675;5780.3930587289415;5808.651480911159;5822.883542329045;5834.562407819346;5889.911651325147;5950.940490595039;5964.284285885721;6035.728392222272;6080.422359875804;5873.4126587340925;5883.146943693226;5883.794067207627;5887.264091988502;5888.941110588839 20:15:40 INFO - PROCESS | 21498 | |1;ext.html;616.4144063140018;636.3032479435019;665.0722242471752;671.5030877150909;673.3165836675778;34702;37076;37117.628823711755;37142.62857371425;37154.8142259289;47630.761846190806;48653;48708.75645621776;52405.21392179111;52461.475385246136;9274.397164184324;9410.670626528074;9413.952930235373;9415.576299066524;9422.34043616947;8604;9304.395214311082;9395.34232603718;10894.291871028357;11111.83332250017;11260.718482037933;11334.773304533905;11339.546418143305;11479;11500.137489688264;11342.659720208407;11359.602413915472;11402.657920262405;11410.486528106214;11413.144014198886;8497.575121243914;10814.78370432587;11050.557977680883;11090.833637495443;11143.777124457547;45339.54660453419;45628;45919.77040114811;45988.77005614951;46027.53972460266;3742.7567208131386;3865.9226815463535;3866.613338666111;3867.7679339239735;3956.4065390191413;34819.303613927834;34921;34931.4760278596;35286.47070293948;35460.29079418397;4666.136764698507;4702.905941881178;4741.644376671763;4780.569748722597;4826.628849918109;368164;370166;370321;370975;372308;61308;61475;61646;61685.69157154275;61695.38304616986;64721.67639161868;64833.67583162054;64910.67544662341;64991.35008649853;65165;16089.758653620205;16387.180640967905;16394.83605163933;16397.754033689504;16415.753763693556;12943.223406595635;13005.154664946747;13034.80447793284;13038.739225215442;13048.673783155307;15641.296141673653;16849.831501685072;17276.827231727773;17369;17798.733019004725;6119.143319935212;6132.6627035513475;6169.907451388232;6194.225721784777;6235.064740288993;1028.7685270814006;1078.6440474643348;1136.3127991768338;1139.5441823270592;1162.7267592115845;34820.477692834625;34865.82567087148;35162.824185878904;35218.47172292418;35294.64705352914;1178.4755783676183;1206.166626727387;1224.4489979509256;1262.8547717012477;1266.3478308671042;17424.215910284067;18109.63780724378;18326.358577449726;18382.540436488925;18439.354622588413;598458;601415;601720;602584;602659;64832;65004;65083;65200.67399662971;65429.34570654233;1313.8686131386976;1419.8035191200158;1461.0868207370393;1465.0403985389587;1466;33696.663033369354;35885.28229435449;36384.818075909454;36455.27089458143;36607.45088823723;54810.355689329386;59165.704171478865;60761;60918;65803;560685;565865;566048;566183;566322;28983.420331593672;30250.54624180683;30548;32341.35317293594;32547.34905301928;52496.47503525069;52862;52942.47057529375;53199;53419.465805341446;43998;44738.3289250655;44751;46148.538514614425;48337.51662483471;45736.62790116302;46397.76801115973;46844.23596898805;47121.293180603;47235.527644723115;45477.54522454733;45572.54427455815;45931;47049;47141;37194;39969.40045899372;40338.596614033486;40515.1896962065;40775;30454.390912182076;30509.23726906845;30696;30773.538396924512;30930.536041958938 20:15:40 INFO - PROCESS | 21498 | |2;jquery.html;396.30052956531745;404.2702921227184;411.5144129926686;424.2448441773642;441.14858323435755;17400.39098631548;20261.39215823524;20344.59310813783;20530.384088477353;20536.178552857906;19242.807571924277;19425.805741942575;19668.50828729279;19811.60376792467;20140.697889531668;5389.218563308318;5684.573656975723;5936;5945.524358051367;5965.254343207099;4056.736312139716;5806;5873.236479257691;5962.910556341659;6068.605540639866;6088.330283668793;6112.327643959183;6123.387661233864;6127.448529632332;6130;5909.556783241253;5997.1304160896425;6018.729157187937;6052.243469566304;6055.939440605582;5419.187121931703;5898.7640494380175;5900.911486327708;5905.261842269716;5909.143174239711;14130.646733831634;14773.482928097465;14797.482088126972;14935.103893766409;14941.626459338495;2362.2795047510504;2427.4295540547955;2454.864982425966;2505.974940250592;2631.8815653295646;11045.83431248532;11098.500567474483;11109.666709998713;11139.164562657834;14992.800575953897;2054.116729806183;2093.6126816538836;2110.978890211109;2141.2719675310314;2237.641977283643;75139;82199.5890020546;83258.58370708168;83311.16688833156;83902.58048709718;11268;11810.055195584333;11862.762744745056;11872;11920.403979800974;16769.413070542472;16833.32666693343;16850.662986740197;16860.072696001593;16868.746968795476;8228.30059444942;8440.493570385674;8463;8466.365022623328;8531.488110713253;8006.1993800619475;8053.6375863086305;8215.794605134919;8274;8293.502389856629;4872.585830204473;4929.802807887644;4966.975165124151;5023.723695196799;5185.9740701297005;2060.9690854637192;2067.410787925434;2078.8752674839557;2102.6635738282107;2107.3467225159943;815.9930655534375;918.6004088221512;1026.9435181064964;1046.3889177901003;1078.2829418436652;19145.329913452963;19554.608907821763;20076.196952122045;20657.276995305092;21018.894905525376;990.3117333453325;1035.1201478743158;1039.7920415916847;1060.1783617696346;1071.2912903918238;10988.340699558052;11164.832527512093;11752.765959574293;11886.34625095628;12311.322877241837;382681;386710;388200;545475;557874;89899.55050224708;91054.54472727593;94226.52015813372;99930.5003474978;108696;1036.7926414717087;1054.3568423261804;1086.9076128529164;1091.1816137896578;1091.612477570467;18452.446426607225;18833.811661883752;18980.810191897905;19057;19099.331523396613;41728.58271417247;43281.134377312905;45278;46666;46685.29972050354;59475.70262148661;61017;65870.34129658643;67465.32534674786;67663.3233667657;5792.304923409218;7711.26742959418;8649.783755406164;8837.425567338103;9094.681686140953;13085.672858178621;13194.274314912387;13271.66820829487;13841.377138029015;13870.4451821926;11914.285142891455;11953.940230298793;12470.314132722424;12481.500739970299;12493.562725304571;5576.944230557642;9164.633414663338;12052.819207711716;12168.148229623841;12321.630351089481;12456.626301210978;12488.625341239778;12494.937525312314;12540.247585144556;12870.485180592672;7988.6405111768945;8674.436161649473;8727.127287271222;8821.60302786389;8828.690995815115;10795.244332896937;11628.6511404658;12161.270323780602;12196.329201893981;12302 20:15:40 INFO - PROCESS | 21498 | |3;mootools.html;1538.3538913656266;1579.2419638573485;1766.8321509456605;1845.9446216613505;1887.8017808130137;9285.907140928588;10095.949520252389;10113.39319640822;10118.291719579633;10125.797484050314;8571.657133714643;9265.629374825015;9523.904760952388;9551.856722149156;9927.205823534117;2476.5789815731323;2518.181590982929;2520.836145650536;2521.7856482199036;2522.583773677341;2461.126300163443;2502.962455563168;2520.962185567218;2523.5457617628817;2524.3058159006237;2425.63615457681;2434.549608322457;2463.3718401807514;2471.9011239550396;2473.0478765675252;2420.8668523231304;2435.4641978764644;2435.8782060896974;2442.1086303499274;2442.7068751749816;2397;2413.9517209655883;2424.5514579802793;2425.151197081025;2437.9512409751705;9856.950715246378;9954.70135895917;9963.302568820185;9972.15236704881;9977.052180042885;5416.133418653036;5517.69652669103;5518.117101263779;5576.888462230732;5597.412271711493;3455.809930453849;3574.9285014299567;3642.326169658595;3674.9448758268645;3683.963160368389;1148.253635137159;1160.7388337623968;1175.8882906123904;1177.6937996120985;1192.6362459449865;13478.595642130751;13492.865071349259;13515.053946223768;13518.053736238468;13534.729305413935;4799.808007679654;4827.879303017382;4969.925451118236;4989.3264409305;5069.366329208849;11310.377929213972;11317.547298108148;11328.150388720878;11329;11331.093512518979;5613.663180209201;5634.7182640867795;5651.886962260732;5654.236678048411;5662.405447428043;4495.303227999665;4553.954460455353;5012.348394708664;5093.643444958891;5161.122609156452;4399.076193999296;4445.333200020023;4455.509893911667;4486.327050942385;4530.750808705553;1328.85382607915;1366.1461586508433;1382.315753701924;1387.0429403711407;1397.706481638847;951.5052172870069;1037.3412882819314;1044.8223801953686;1065.2117433099606;1079.0989523747758;6314.62112273265;6747.730090796412;6778.322167783281;6787.864242715118;6950.165980082423;470.9317149013374;499.03187815638273;503.6046703337862;506.3594552890596;524.5279248676222;4692.835750748707;4698.412698412699;4722.456917454537;4739.336492890998;4759.690620109751;11913.166078374605;12345.259284442962;12370.938145309216;12422.254664720145;12425.502979880703;10500.159987201005;10828.945855270673;10868.456577171268;12305.93847030759;12357.073219508393;8749.212570868653;8902.73291801247;8933.508657023796;8970.551472426352;8974.28205743539;6645.468362531083;6657.788295402371;6913.170419549686;6968.651567421507;6989.30106989297;11108.222424430212;11272.605458808903;11293.774124517628;11353.943230283796;11401.657950261506;11341.149413794163;11401.885981140082;11612.361320127136;11709.414529273672;11747.647570572897;7824.100228473799;8141.43009989295;8250.174982501701;8489.490630562186;8608.870866936875;9728.367656102331;9811.607535698493;9816.80366392654;9819.509024548886;9822.361546499455;8544.829103417773;8650.13498650155;8693;8834.293256539333;8964.731058068268;8313.542755148525;8411.285040771358;8411.285040771603;8483.703070392507;8615.956920215358;8046.275835174628;8058.879116813136;8123.634436450492;8126.959365203136;8132.83734325322;5515.806946756844;5666;5722.5135863450405;5770.307563092288;5799.188113664006;5786.537077033743;5859.619124756877;5894.20428242199;5903.49820265265;5978.073398623221 20:15:40 INFO - PROCESS | 21498 | |4;prototype.html;344.29247895574593;344.6467370944781;350.9646542699037;351.99648003519974;358.11724100093267;5502.834914952553;5572.275604171452;5579.581531385147;5609.691466969315;5611.158326251064;5508.834734957958;5557.833265002057;5577.609567330289;5585.748641311141;5598.524125449342;3878.030492376906;4150.273702102135;4150.730202536825;4317.445719540963;4319.6760242981745;4333.891652708676;4336.3278691802825;4341.761203133827;4342.848000319989;4347.41309923161;4315.546867578906;4335.937695264649;4362.302031674933;4363.752667376304;4367.323064924942;4081.163361510898;4176.7076304658685;4305;4306.35404689296;4335.219660461115;3824.240849209367;3830.578636349999;3835.463035175078;3838.4626152338697;3851.364524853396;5369.140937449989;5379.085555455582;5393.649412788195;5396.1366181410785;5432.538234250093;4790.928136077961;4935.2973224237585;4949.282354058641;4951.504849515056;4986.825461108844;5723.370429252779;5731.656100633975;5732.82801515951;5740.167675687044;5740.79443316904;4233.993864356311;4241.724287921275;4257.787110644486;4260.318349064166;4260.510041345223;5705.77176912923;5949.940500594982;5967.820965371003;6016.1878146450545;6038.667873266969;4197.517285512145;4206.684498662613;4208.326667733148;4223.619874211275;4230.682698797603;4299.161663475592;4302.290122129876;4309.870703878889;4322.589354011364;4329.502107257706;4723.095296392694;4864.343313652683;4900.975495122501;4934.9013019739405;4960.677555958851;5774.556360909773;5798.507126894261;5805.7387417566315;5805.8548536286935;5810.605454690928;1471.1320321010655;1477.4090363854543;1480.9925950370396;1482.3477669825243;1487.300968544804;937.6624415210518;948.4309414351334;948.9478078705738;953.7853982853941;973.2262851033446;5029.371328583927;5031.773570189277;5044.243363495505;5050.9494905051215;5055.292259083732;5275.657082289716;5283.128283833124;5286.60350473716;5298.92051619226;5319.414864364917;4721.480637129913;4964.801407943642;4984.626153038536;5071.8985620287385;5079.720615366116;2759.4756996170663;2771.9168424947284;2777.6389069420848;2781.527140386099;2816.690164081949;5409.350877894678;5436.374816896028;5437.347518297751;5467.398586155519;5486.753096110685;5150.047630210025;5236.214567814782;5282.022825777243;5774.88450230993;5913.2608423947;6110.480609148183;6148.569600128038;6155.876882462415;6158.568900177034;6163.722632481459;5972;6009.287353104344;6023.909641355295;6028.788992385245;6030.48740857032;5644.491995720241;5954.374790647093;5977.880442391041;5992.490638295793;6001.2498437695285;6176.382361763787;6176.752929882754;6176.7838125665385;6179.0031834561105;6192.328071420601;4210.515790683988;4260.339647354665;4293.441852559147;4306.913861722685;4332.111917057059;6005.579609427473;6013.579049466495;6021.698915054142;6029.457348838626;6031.517478601613;5886.52907767369;5895.41045895407;5907.527397808251;5925.970370148121;5932.614380065282;5686;5834.620749651259;5858.8242352729485;5860.443257890664;5869.943410186207;5794.101914203306;5805.796797112081;5817.621854579439;5818.912716309174;5834.679092649775;5613.298337707786;5867.911981320369;5912.645241285537;5920.526357891272;5929.733162007807;5729.455701708331;5732.308968854115;5742.971285143548;5772.191893135048;5802.796902108406 20:15:40 INFO - PROCESS | 21498 | |5;yui.html;810.2707563193126;829.5603330234977;883.9513826739529;902.9819403611928;906.8866391701038;15347.693046139084;16137.919310403446;16169.514914552583;16170.83829161711;16183.757243641356;16121.758173627404;16455.34178632856;16480.752788708178;16483.58791030225;16492.34030638776;713.4185638704453;853.4068822168596;879.1120967822513;881.2949640287776;883.8630012348065;885.2386947225414;887.1084560017206;887.7647423432795;888.3470649072958;888.3515034025165;941.7928055827707;942.3968660057569;942.6606421688186;943.3490891284991;943.9528023598826;759.7340930674275;899.0829354058889;921.3182245138617;925.22281283722;936.2182577547721;683.733343995842;684.0286792754301;684.3567046975827;684.9212340580847;685.1777866560147;15642.608934776608;15660.216989150496;15666.138362390064;15666.608334791608;15687.450939217188;14469.92765036168;14578.416863325454;14710;14822.110673359633;14958;12361.876381236163;12421.565245216465;12493.25040497573;12605.369731513387;12686.365681715877;4210.410542524049;4218.303979843353;4297.484301883794;4618.1687296286655;4742.834000809989;82774.58612706898;83070;86826.5658671709;87274.56362718146;88588.11411885863;13033.608991730263;13034.217946923214;13093.083484156017;13118.147320424141;13167.736645267041;24435.144769932966;24647.260582182564;24704.629430558554;25042.123525676514;25042.499150016898;8870.86693699595;8894.066123057115;8915.687950921816;9156.359054866229;9172.541372931326;4966.503349665077;5064.873378165576;5151.5621172200035;5172.250023746536;5175.378954525481;4085.20338533983;4269.573042695705;4273.572642735701;4273.893152671208;4275.080857615628;707.6391040569347;707.7558242406361;717.1573401253506;725.3435640745134;736.4292673178329;679.218898266987;685.3948232298147;803.236924921327;808.0626473290954;837.9581020948929;9774.31579789408;10221.182305415548;10222.182225422097;10555.15558755313;10580.841287380696;784.8175647748321;805.9479553903345;811.4036183405193;825.7522743177022;830.8836762853206;4989.90020199594;5013.649044566845;5027.120253955543;5041.092603331435;5054.974725126351;127491;132671.33664331617;132811;135363;140464;115569.4221528887;115757;116295;118065.40967295108;119904;40121.1975760489;76727;77559;78214.21785782183;98714.50642746741;9720.611175552898;9879.110880020833;9906.851397228904;9923.255755818487;10040.849387259052;54244;54726.17910731262;55467.72266138643;55592.44407556034;56526.43473565376;75484.24515754772;78596.21403786118;81843.5907820457;82487.5875620642;83335.583322083;12953.287569183409;13098.541551045668;13216.669583260495;13500.32498375097;13601.79597306061;14515.274236288355;14670.77993830072;14713.92643036778;14817.110973341634;15095.54713358601;13895.444182232597;14408.20754858493;14563.708725825636;14857.182854943081;14863.40546378176;12958.416871240628;13014.80477792815;13723.519676811664;13768.586942391745;14166.858331416553;13012.739745205232;13515.391807368494;13517.59447216585;13599.520862447358;14043.722611472529;11427.771444570895;11556.94221528887;11583.652490424964;12034.879651203375;12246.448909798903;11373.715657108638;11662.88337116618;11684.298942063164;12257.816132757838;12321.876781232073 20:15:40 INFO - PROCESS | 21498 | __end_tp_report 20:15:40 INFO - PROCESS | 21498 | __start_cc_report 20:15:40 INFO - PROCESS | 21498 | _x_x_mozilla_cycle_collect,1072 20:15:40 INFO - PROCESS | 21498 | __end_cc_report 20:15:40 INFO - PROCESS | 21498 | __startTimestamp1472613340773__endTimestamp 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | ------- Summary: start ------- 20:15:40 INFO - PROCESS | 21498 | Number of tests: 6 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#0] dojo.html Cycles:165 Average:5598.76 Median:5829.91 stddev:649.73 (11.1%) stddev-sans-first:622.33 20:15:40 INFO - PROCESS | 21498 | Values: 3135.6 3164.5 3178.0 3184.0 3213.4 5541.1 5631.1 5650.7 5651.3 5674.0 5569.9 5674.4 5676.5 5680.5 5717.2 5033.3 5091.3 5107.9 5136.5 5201.4 5158.7 5228.7 5234.4 5235.5 5239.9 5046.3 5095.3 5101.7 5109.4 5120.8 5198.8 5203.3 5219.3 5230.2 5261.0 4927.0 4976.4 5020.2 5127.8 5133.4 5498.1 5524.3 5545.9 5557.7 5588.4 6044.2 6053.8 6060.1 6074.5 6084.4 5816.0 5850.2 5867.0 5964.8 6078.5 5553.1 5578.4 5584.3 5596.3 5621.4 6037.6 6114.3 6124.9 6127.0 6129.8 5842.8 5880.0 5885.1 5979.2 5985.8 6025.1 6046.2 6055.3 6059.8 6064.8 5795.5 6022.8 6024.8 6025.3 6028.2 5918.4 5989.6 6023.6 6129.3 6142.5 4609.4 4617.4 4624.5 4635.6 4656.6 4045.5 4092.2 4107.4 4113.0 4122.0 5724.5 5732.1 5733.1 5827.7 5862.0 6111.7 6124.9 6139.2 6142.7 6143.8 5514.8 5817.8 5824.0 5829.9 5833.0 5069.9 5135.7 5139.4 5176.0 5207.9 6348.7 6351.2 6358.8 6359.4 6366.6 5735.5 6170.6 6178.4 6179.4 6180.0 6084.6 6124.8 6129.0 6154.5 6196.6 5876.4 5917.7 5925.6 5928.0 5974.6 5797.9 6040.7 6050.9 6053.3 6066.8 5679.9 5820.2 5865.2 5921.3 5941.0 5860.8 5875.1 5882.0 5888.3 5890.4 5544.1 5780.4 5808.7 5822.9 5834.6 5889.9 5950.9 5964.3 6035.7 6080.4 5873.4 5883.1 5883.8 5887.3 5888.9 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#1] ext.html Cycles:180 Average:68223.92 Median:3912.09 stddev:138878.19 (3550.0%) stddev-sans-first:139175.02 20:15:40 INFO - PROCESS | 21498 | Values: 616.4 636.3 665.1 671.5 673.3 34702.0 37076.0 37117.6 37142.6 37154.8 47630.8 48653.0 48708.8 52405.2 52461.5 9274.4 9410.7 9414.0 9415.6 9422.3 8604.0 9304.4 9395.3 10894.3 11111.8 11260.7 11334.8 11339.5 11479.0 11500.1 11342.7 11359.6 11402.7 11410.5 11413.1 8497.6 10814.8 11050.6 11090.8 11143.8 45339.5 45628.0 45919.8 45988.8 46027.5 3742.8 3865.9 3866.6 3867.8 3956.4 34819.3 34921.0 34931.5 35286.5 35460.3 4666.1 4702.9 4741.6 4780.6 4826.6 368164.0 370166.0 370321.0 370975.0 372308.0 61308.0 61475.0 61646.0 61685.7 61695.4 64721.7 64833.7 64910.7 64991.4 65165.0 16089.8 16387.2 16394.8 16397.8 16415.8 12943.2 13005.2 13034.8 13038.7 13048.7 15641.3 16849.8 17276.8 17369.0 17798.7 6119.1 6132.7 6169.9 6194.2 6235.1 1028.8 1078.6 1136.3 1139.5 1162.7 34820.5 34865.8 35162.8 35218.5 35294.6 1178.5 1206.2 1224.4 1262.9 1266.3 17424.2 18109.6 18326.4 18382.5 18439.4 598458.0 601415.0 601720.0 602584.0 602659.0 64832.0 65004.0 65083.0 65200.7 65429.3 1313.9 1419.8 1461.1 1465.0 1466.0 33696.7 35885.3 36384.8 36455.3 36607.5 54810.4 59165.7 60761.0 60918.0 65803.0 560685.0 565865.0 566048.0 566183.0 566322.0 28983.4 30250.5 30548.0 32341.4 32547.3 52496.5 52862.0 52942.5 53199.0 53419.5 43998.0 44738.3 44751.0 46148.5 48337.5 45736.6 46397.8 46844.2 47121.3 47235.5 45477.5 45572.5 45931.0 47049.0 47141.0 37194.0 39969.4 40338.6 40515.2 40775.0 30454.4 30509.2 30696.0 30773.5 30930.5 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#2] jquery.html Cycles:180 Average:28411.55 Median:2299.96 stddev:76214.19 (3313.7%) stddev-sans-first:76398.97 20:15:40 INFO - PROCESS | 21498 | Values: 396.3 404.3 411.5 424.2 441.1 17400.4 20261.4 20344.6 20530.4 20536.2 19242.8 19425.8 19668.5 19811.6 20140.7 5389.2 5684.6 5936.0 5945.5 5965.3 4056.7 5806.0 5873.2 5962.9 6068.6 6088.3 6112.3 6123.4 6127.4 6130.0 5909.6 5997.1 6018.7 6052.2 6055.9 5419.2 5898.8 5900.9 5905.3 5909.1 14130.6 14773.5 14797.5 14935.1 14941.6 2362.3 2427.4 2454.9 2506.0 2631.9 11045.8 11098.5 11109.7 11139.2 14992.8 2054.1 2093.6 2111.0 2141.3 2237.6 75139.0 82199.6 83258.6 83311.2 83902.6 11268.0 11810.1 11862.8 11872.0 11920.4 16769.4 16833.3 16850.7 16860.1 16868.7 8228.3 8440.5 8463.0 8466.4 8531.5 8006.2 8053.6 8215.8 8274.0 8293.5 4872.6 4929.8 4967.0 5023.7 5186.0 2061.0 2067.4 2078.9 2102.7 2107.3 816.0 918.6 1026.9 1046.4 1078.3 19145.3 19554.6 20076.2 20657.3 21018.9 990.3 1035.1 1039.8 1060.2 1071.3 10988.3 11164.8 11752.8 11886.3 12311.3 382681.0 386710.0 388200.0 545475.0 557874.0 89899.6 91054.5 94226.5 99930.5 108696.0 1036.8 1054.4 1086.9 1091.2 1091.6 18452.4 18833.8 18980.8 19057.0 19099.3 41728.6 43281.1 45278.0 46666.0 46685.3 59475.7 61017.0 65870.3 67465.3 67663.3 5792.3 7711.3 8649.8 8837.4 9094.7 13085.7 13194.3 13271.7 13841.4 13870.4 11914.3 11953.9 12470.3 12481.5 12493.6 5576.9 9164.6 12052.8 12168.1 12321.6 12456.6 12488.6 12494.9 12540.2 12870.5 7988.6 8674.4 8727.1 8821.6 8828.7 10795.2 11628.7 12161.3 12196.3 12302.0 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#3] mootools.html Cycles:180 Average:6385.42 Median:4695.62 stddev:3702.10 (78.8%) stddev-sans-first:3694.56 20:15:40 INFO - PROCESS | 21498 | Values: 1538.4 1579.2 1766.8 1845.9 1887.8 9285.9 10095.9 10113.4 10118.3 10125.8 8571.7 9265.6 9523.9 9551.9 9927.2 2476.6 2518.2 2520.8 2521.8 2522.6 2461.1 2503.0 2521.0 2523.5 2524.3 2425.6 2434.5 2463.4 2471.9 2473.0 2420.9 2435.5 2435.9 2442.1 2442.7 2397.0 2414.0 2424.6 2425.2 2438.0 9857.0 9954.7 9963.3 9972.2 9977.1 5416.1 5517.7 5518.1 5576.9 5597.4 3455.8 3574.9 3642.3 3674.9 3684.0 1148.3 1160.7 1175.9 1177.7 1192.6 13478.6 13492.9 13515.1 13518.1 13534.7 4799.8 4827.9 4969.9 4989.3 5069.4 11310.4 11317.5 11328.2 11329.0 11331.1 5613.7 5634.7 5651.9 5654.2 5662.4 4495.3 4554.0 5012.3 5093.6 5161.1 4399.1 4445.3 4455.5 4486.3 4530.8 1328.9 1366.1 1382.3 1387.0 1397.7 951.5 1037.3 1044.8 1065.2 1079.1 6314.6 6747.7 6778.3 6787.9 6950.2 470.9 499.0 503.6 506.4 524.5 4692.8 4698.4 4722.5 4739.3 4759.7 11913.2 12345.3 12370.9 12422.3 12425.5 10500.2 10828.9 10868.5 12305.9 12357.1 8749.2 8902.7 8933.5 8970.6 8974.3 6645.5 6657.8 6913.2 6968.7 6989.3 11108.2 11272.6 11293.8 11353.9 11401.7 11341.1 11401.9 11612.4 11709.4 11747.6 7824.1 8141.4 8250.2 8489.5 8608.9 9728.4 9811.6 9816.8 9819.5 9822.4 8544.8 8650.1 8693.0 8834.3 8964.7 8313.5 8411.3 8411.3 8483.7 8616.0 8046.3 8058.9 8123.6 8127.0 8132.8 5515.8 5666.0 5722.5 5770.3 5799.2 5786.5 5859.6 5894.2 5903.5 5978.1 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#4] prototype.html Cycles:180 Average:4805.49 Median:5394.89 stddev:1421.11 (26.3%) stddev-sans-first:1385.08 20:15:40 INFO - PROCESS | 21498 | Values: 344.3 344.6 351.0 352.0 358.1 5502.8 5572.3 5579.6 5609.7 5611.2 5508.8 5557.8 5577.6 5585.7 5598.5 3878.0 4150.3 4150.7 4317.4 4319.7 4333.9 4336.3 4341.8 4342.8 4347.4 4315.5 4335.9 4362.3 4363.8 4367.3 4081.2 4176.7 4305.0 4306.4 4335.2 3824.2 3830.6 3835.5 3838.5 3851.4 5369.1 5379.1 5393.6 5396.1 5432.5 4790.9 4935.3 4949.3 4951.5 4986.8 5723.4 5731.7 5732.8 5740.2 5740.8 4234.0 4241.7 4257.8 4260.3 4260.5 5705.8 5949.9 5967.8 6016.2 6038.7 4197.5 4206.7 4208.3 4223.6 4230.7 4299.2 4302.3 4309.9 4322.6 4329.5 4723.1 4864.3 4901.0 4934.9 4960.7 5774.6 5798.5 5805.7 5805.9 5810.6 1471.1 1477.4 1481.0 1482.3 1487.3 937.7 948.4 948.9 953.8 973.2 5029.4 5031.8 5044.2 5050.9 5055.3 5275.7 5283.1 5286.6 5298.9 5319.4 4721.5 4964.8 4984.6 5071.9 5079.7 2759.5 2771.9 2777.6 2781.5 2816.7 5409.4 5436.4 5437.3 5467.4 5486.8 5150.0 5236.2 5282.0 5774.9 5913.3 6110.5 6148.6 6155.9 6158.6 6163.7 5972.0 6009.3 6023.9 6028.8 6030.5 5644.5 5954.4 5977.9 5992.5 6001.2 6176.4 6176.8 6176.8 6179.0 6192.3 4210.5 4260.3 4293.4 4306.9 4332.1 6005.6 6013.6 6021.7 6029.5 6031.5 5886.5 5895.4 5907.5 5926.0 5932.6 5686.0 5834.6 5858.8 5860.4 5869.9 5794.1 5805.8 5817.6 5818.9 5834.7 5613.3 5867.9 5912.6 5920.5 5929.7 5729.5 5732.3 5743.0 5772.2 5802.8 20:15:40 INFO - PROCESS | 21498 | 20:15:40 INFO - PROCESS | 21498 | [#5] yui.html Cycles:180 Average:22452.12 Median:4273.73 stddev:33339.04 (780.1%) stddev-sans-first:33392.96 20:15:40 INFO - PROCESS | 21498 | Values: 810.3 829.6 884.0 903.0 906.9 15347.7 16137.9 16169.5 16170.8 16183.8 16121.8 16455.3 16480.8 16483.6 16492.3 713.4 853.4 879.1 881.3 883.9 885.2 887.1 887.8 888.3 888.4 941.8 942.4 942.7 943.3 944.0 759.7 899.1 921.3 925.2 936.2 683.7 684.0 684.4 684.9 685.2 15642.6 15660.2 15666.1 15666.6 15687.5 14469.9 14578.4 14710.0 14822.1 14958.0 12361.9 12421.6 12493.3 12605.4 12686.4 4210.4 4218.3 4297.5 4618.2 4742.8 82774.6 83070.0 86826.6 87274.6 88588.1 13033.6 13034.2 13093.1 13118.1 13167.7 24435.1 24647.3 24704.6 25042.1 25042.5 8870.9 8894.1 8915.7 9156.4 9172.5 4966.5 5064.9 5151.6 5172.3 5175.4 4085.2 4269.6 4273.6 4273.9 4275.1 707.6 707.8 717.2 725.3 736.4 679.2 685.4 803.2 808.1 838.0 9774.3 10221.2 10222.2 10555.2 10580.8 784.8 805.9 811.4 825.8 830.9 4989.9 5013.6 5027.1 5041.1 5055.0 127491.0 132671.3 132811.0 135363.0 140464.0 115569.4 115757.0 116295.0 118065.4 119904.0 40121.2 76727.0 77559.0 78214.2 98714.5 9720.6 9879.1 9906.9 9923.3 10040.8 54244.0 54726.2 55467.7 55592.4 56526.4 75484.2 78596.2 81843.6 82487.6 83335.6 12953.3 13098.5 13216.7 13500.3 13601.8 14515.3 14670.8 14713.9 14817.1 15095.5 13895.4 14408.2 14563.7 14857.2 14863.4 12958.4 13014.8 13723.5 13768.6 14166.9 13012.7 13515.4 13517.6 13599.5 14043.7 11427.8 11556.9 11583.7 12034.9 12246.4 11373.7 11662.9 11684.3 12257.8 12321.9 20:15:40 INFO - PROCESS | 21498 | -------- Summary: end -------- 20:15:40 INFO - PROCESS | 21498 | 20:15:41 INFO - TEST-INFO | 21498: exit 0 20:15:41 INFO - TEST-OK | dromaeo_css | took 1113526ms 20:15:41 INFO - TEST-START | kraken 20:15:41 INFO - operating with platform_type : linux_ 20:15:41 INFO - Initialising browser for kraken test... 20:15:41 INFO - TEST-INFO | started process 21707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Fn1iT/profile http://localhost:33504/getInfo.html) 20:15:45 INFO - PROCESS | 21707 | __metrics Screen width/height:1600/1200 20:15:45 INFO - PROCESS | 21707 | colorDepth:24 20:15:45 INFO - PROCESS | 21707 | Browser inner width/height: 1024/697 20:15:45 INFO - PROCESS | 21707 | __metrics 20:15:46 INFO - PROCESS | 21707 | 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" 20:15:46 INFO - PROCESS | 21707 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:15:46 INFO - PROCESS | 21707 | console.error: PushService: 20:15:46 INFO - PROCESS | 21707 | stateChangeProcessEnqueue: Error transitioning state 20:15:46 INFO - PROCESS | 21707 | UnknownError 20:15:47 INFO - TEST-INFO | 21707: exit 0 20:15:47 INFO - Browser initialized. 20:15:47 INFO - Running cycle 1/1 for kraken test... 20:15:47 INFO - TEST-INFO | started process 21797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Fn1iT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:15:59 INFO - PROCESS | 21797 | RSS: Main: 145231872 20:15:59 INFO - PROCESS | 21797 | 20:16:01 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33504/tests/kraken/driver.html?testName=audio-beat-detection) 20:16:02 INFO - PROCESS | 21797 | RSS: Main: 191225856 20:16:02 INFO - PROCESS | 21797 | 20:16:05 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33504/tests/kraken/driver.html?testName=audio-dft) 20:16:05 INFO - PROCESS | 21797 | RSS: Main: 181764096 20:16:05 INFO - PROCESS | 21797 | 20:16:08 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33504/tests/kraken/driver.html?testName=audio-fft) 20:16:09 INFO - PROCESS | 21797 | RSS: Main: 269225984 20:16:09 INFO - PROCESS | 21797 | 20:16:11 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33504/tests/kraken/driver.html?testName=audio-oscillator) 20:16:11 INFO - PROCESS | 21797 | RSS: Main: 174354432 20:16:11 INFO - PROCESS | 21797 | 20:16:13 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33504/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:16:14 INFO - PROCESS | 21797 | RSS: Main: 176881664 20:16:14 INFO - PROCESS | 21797 | 20:16:17 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33504/tests/kraken/driver.html?testName=imaging-darkroom) 20:16:18 INFO - PROCESS | 21797 | RSS: Main: 213434368 20:16:18 INFO - PROCESS | 21797 | 20:16:21 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33504/tests/kraken/driver.html?testName=imaging-desaturate) 20:16:22 INFO - PROCESS | 21797 | RSS: Main: 211464192 20:16:22 INFO - PROCESS | 21797 | 20:16:24 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33504/tests/kraken/driver.html?testName=json-parse-financial) 20:16:25 INFO - PROCESS | 21797 | RSS: Main: 211869696 20:16:25 INFO - PROCESS | 21797 | 20:16:26 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33504/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:16:27 INFO - PROCESS | 21797 | RSS: Main: 176050176 20:16:27 INFO - PROCESS | 21797 | 20:16:29 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:16:29 INFO - PROCESS | 21797 | RSS: Main: 181305344 20:16:29 INFO - PROCESS | 21797 | 20:16:31 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:16:32 INFO - PROCESS | 21797 | RSS: Main: 186503168 20:16:32 INFO - PROCESS | 21797 | 20:16:34 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:16:35 INFO - PROCESS | 21797 | RSS: Main: 181317632 20:16:35 INFO - PROCESS | 21797 | 20:16:38 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:16:38 INFO - PROCESS | 21797 | RSS: Main: 184639488 20:16:38 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | Cycle 1(1): loaded http://localhost:33504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33504/tests/kraken/driver.html?testName=ai-astar) 20:16:40 INFO - PROCESS | 21797 | RSS: Main: 236113920 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | __start_tp_report 20:16:40 INFO - PROCESS | 21797 | _x_x_mozilla_page_load 20:16:40 INFO - PROCESS | 21797 | _x_x_mozilla_page_load_details 20:16:40 INFO - PROCESS | 21797 | |i|pagename|runs| 20:16:40 INFO - PROCESS | 21797 | |0;ai-astar;103;112;109;112;109;110;109;109;112;111 20:16:40 INFO - PROCESS | 21797 | |1;audio-beat-detection;142;115;121;175;116;117;144;115;149;114 20:16:40 INFO - PROCESS | 21797 | |2;audio-dft;162;161;160;160;161;161;162;160;159;160 20:16:40 INFO - PROCESS | 21797 | |3;audio-fft;75;75;75;109;76;77;76;75;102;74 20:16:40 INFO - PROCESS | 21797 | |4;audio-oscillator;83;82;123;83;83;84;118;82;82;119 20:16:40 INFO - PROCESS | 21797 | |5;imaging-gaussian-blur;117;117;117;117;116;116;117;117;117;116 20:16:40 INFO - PROCESS | 21797 | |6;imaging-darkroom;146;146;144;146;147;146;146;146;145;146 20:16:40 INFO - PROCESS | 21797 | |7;imaging-desaturate;96;94;94;94;95;95;95;95;94;95 20:16:40 INFO - PROCESS | 21797 | |8;json-parse-financial;52;52;49;50;49;50;51;49;49;50 20:16:40 INFO - PROCESS | 21797 | |9;json-stringify-tinderbox;60;63;59;58;59;60;60;60;59;60 20:16:40 INFO - PROCESS | 21797 | |10;stanford-crypto-aes;82;75;80;72;70;71;72;72;72;76 20:16:40 INFO - PROCESS | 21797 | |11;stanford-crypto-ccm;119;114;116;123;115;114;116;116;147;118 20:16:40 INFO - PROCESS | 21797 | |12;stanford-crypto-pbkdf2;152;157;156;153;156;157;160;157;154;155 20:16:40 INFO - PROCESS | 21797 | |13;stanford-crypto-sha256-iterative;62;63;65;63;61;61;61;60;61;60 20:16:40 INFO - PROCESS | 21797 | __end_tp_report 20:16:40 INFO - PROCESS | 21797 | __start_cc_report 20:16:40 INFO - PROCESS | 21797 | _x_x_mozilla_cycle_collect,1657 20:16:40 INFO - PROCESS | 21797 | __end_cc_report 20:16:40 INFO - PROCESS | 21797 | __startTimestamp1472613400594__endTimestamp 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | ------- Summary: start ------- 20:16:40 INFO - PROCESS | 21797 | Number of tests: 14 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.50 stddev:2.67 (2.4%) stddev-sans-first:1.41 20:16:40 INFO - PROCESS | 21797 | Values: 103.0 112.0 109.0 112.0 109.0 110.0 109.0 109.0 112.0 111.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:131.50 stddev:20.74 (15.8%) stddev-sans-first:21.60 20:16:40 INFO - PROCESS | 21797 | Values: 142.0 115.0 121.0 175.0 116.0 117.0 144.0 115.0 149.0 114.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 20:16:40 INFO - PROCESS | 21797 | Values: 162.0 161.0 160.0 160.0 161.0 161.0 162.0 160.0 159.0 160.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:12.83 (17.1%) stddev-sans-first:13.40 20:16:40 INFO - PROCESS | 21797 | Values: 75.0 75.0 75.0 109.0 76.0 77.0 76.0 75.0 102.0 74.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:82.50 stddev:18.06 (21.9%) stddev-sans-first:18.72 20:16:40 INFO - PROCESS | 21797 | Values: 83.0 82.0 123.0 83.0 83.0 84.0 118.0 82.0 82.0 119.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 20:16:40 INFO - PROCESS | 21797 | Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 20:16:40 INFO - PROCESS | 21797 | Values: 146.0 146.0 144.0 146.0 147.0 146.0 146.0 146.0 145.0 146.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.53 20:16:40 INFO - PROCESS | 21797 | Values: 96.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.20 (2.4%) stddev-sans-first:1.05 20:16:40 INFO - PROCESS | 21797 | Values: 52.0 52.0 49.0 50.0 49.0 50.0 51.0 49.0 49.0 50.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:1.32 (2.2%) stddev-sans-first:1.39 20:16:40 INFO - PROCESS | 21797 | Values: 60.0 63.0 59.0 58.0 59.0 60.0 60.0 60.0 59.0 60.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:73.50 stddev:4.02 (5.5%) stddev-sans-first:3.12 20:16:40 INFO - PROCESS | 21797 | Values: 82.0 75.0 80.0 72.0 70.0 71.0 72.0 72.0 72.0 76.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#11] stanford-crypto-ccm Cycles:10 Average:119.80 Median:117.00 stddev:9.93 (8.5%) stddev-sans-first:10.53 20:16:40 INFO - PROCESS | 21797 | Values: 119.0 114.0 116.0 123.0 115.0 114.0 116.0 116.0 147.0 118.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:156.50 stddev:2.31 (1.5%) stddev-sans-first:2.03 20:16:40 INFO - PROCESS | 21797 | Values: 152.0 157.0 156.0 153.0 156.0 157.0 160.0 157.0 154.0 155.0 20:16:40 INFO - PROCESS | 21797 | 20:16:40 INFO - PROCESS | 21797 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:61.50 stddev:1.57 (2.5%) stddev-sans-first:1.66 20:16:40 INFO - PROCESS | 21797 | Values: 62.0 63.0 65.0 63.0 61.0 61.0 61.0 60.0 61.0 60.0 20:16:40 INFO - PROCESS | 21797 | -------- Summary: end -------- 20:16:40 INFO - PROCESS | 21797 | 20:16:41 INFO - TEST-INFO | 21797: exit 0 20:16:41 INFO - TEST-OK | kraken | took 60119ms 20:16:41 INFO - SUITE-END | took 1173s 20:16:41 INFO - Completed test suite (00:19:33) 20:16:41 INFO - javascript benchmark 20:16:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3135.576697145886, 3164.493681011039, 3178.046586024192, 3184.012955983645, 3213.38945600336, 5541.085720856059, 5631.0708733059, 5650.6892120933335, 5651.2935883014625, 5674.0, 5569.916451253234, 5674.375818659944, 5676.545876329894, 5680.460356266159, 5717.2281741964825, 5033.295338652573, 5091.261767043777, 5107.897842043166, 5136.512031357015, 5201.40183878855, 5158.664686795365, 5228.73856307185, 5234.398044224906, 5235.502627250406, 5239.895202095956, 5046.268291097788, 5095.261187127864, 5101.744912754366, 5109.437961824215, 5120.820771273006, 5198.766055527511, 5203.323567936182, 5219.321488206547, 5230.217261311552, 5261.0, 4927.039227350668, 4976.4277108132665, 5020.221865610836, 5127.794888204505, 5133.409657889353, 5498.092814685612, 5524.309461317335, 5545.889082218373, 5557.749901254453, 5588.357338905997, 6044.184035155242, 6053.848653783647, 6060.060690592965, 6074.526036179504, 6084.421979911901, 5816.040353341694, 5850.239468869062, 5866.97327967604, 5964.791232306892, 6078.544109191828, 5553.055980483327, 5578.386377498472, 5584.301962254718, 5596.27248457706, 5621.409751976024, 6037.607555508878, 6114.327423983358, 6124.908126378108, 6126.991097449049, 6129.828674031736, 5842.766289348378, 5880.0, 5885.058390657474, 5979.222701048923, 5985.820425387245, 6025.126356678345, 6046.153538504613, 6055.333913269536, 6059.787907423307, 6064.818055458343, 5795.536357091422, 6022.789202377896, 6024.759009639653, 6025.307089684655, 6028.216331876828, 5918.378570250062, 5989.610675306091, 6023.6385816851125, 6129.328227221949, 6142.498775697809, 4609.377734005933, 4617.376654151646, 4624.537546245416, 4635.582797548237, 4656.627469802408, 4045.514538255369, 4092.1611069730634, 4107.363358679409, 4113.01287690952, 4122.031322639177, 5724.484796368347, 5732.082866741384, 5733.140028995684, 5827.737751801179, 5862.032764593794, 6111.672398433294, 6124.877502449926, 6139.171211886338, 6142.692865356715, 6143.754249830046, 5514.80698175562, 5817.7963771267805, 5824.0390335595, 5829.912551311733, 5832.9708351457975, 5069.8732531687, 5135.717535535581, 5139.408967968731, 5175.974120129451, 5207.921881171786, 6348.746050158034, 6351.17434733491, 6358.77744278948, 6359.395857393541, 6366.649834259068, 5735.483806457439, 6170.629762214281, 6178.443940045418, 6179.3820617937845, 6179.969100154651, 6084.60450070744, 6124.816255512342, 6129.049997250433, 6154.507639388928, 6196.566240363131, 5876.41235876409, 5917.733701983507, 5925.585209035326, 5927.970360148344, 5974.551908606785, 5797.884042319214, 6040.6979651018155, 6050.9394906050375, 6053.273607167168, 6066.757329706938, 5679.8864022720145, 5820.185174075548, 5865.208196893364, 5921.259842519685, 5941.019731744386, 5860.765569377178, 5875.059990401686, 5881.9705901470215, 5888.263967004124, 5890.440408161217, 5544.085225937675, 5780.3930587289415, 5808.651480911159, 5822.883542329045, 5834.562407819346, 5889.911651325147, 5950.940490595039, 5964.284285885721, 6035.728392222272, 6080.422359875804, 5873.4126587340925, 5883.146943693226, 5883.794067207627, 5887.264091988502, 5888.941110588839], "value": 5553.534421647126, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.4144063140018, 636.3032479435019, 665.0722242471752, 671.5030877150909, 673.3165836675778, 34702.0, 37076.0, 37117.628823711755, 37142.62857371425, 37154.8142259289, 47630.761846190806, 48653.0, 48708.75645621776, 52405.21392179111, 52461.475385246136, 9274.397164184324, 9410.670626528074, 9413.952930235373, 9415.576299066524, 9422.34043616947, 8604.0, 9304.395214311082, 9395.34232603718, 10894.291871028357, 11111.83332250017, 11260.718482037933, 11334.773304533905, 11339.546418143305, 11479.0, 11500.137489688264, 11342.659720208407, 11359.602413915472, 11402.657920262405, 11410.486528106214, 11413.144014198886, 8497.575121243914, 10814.78370432587, 11050.557977680883, 11090.833637495443, 11143.777124457547, 45339.54660453419, 45628.0, 45919.77040114811, 45988.77005614951, 46027.53972460266, 3742.7567208131386, 3865.9226815463535, 3866.613338666111, 3867.7679339239735, 3956.4065390191413, 34819.303613927834, 34921.0, 34931.4760278596, 35286.47070293948, 35460.29079418397, 4666.136764698507, 4702.905941881178, 4741.644376671763, 4780.569748722597, 4826.628849918109, 368164.0, 370166.0, 370321.0, 370975.0, 372308.0, 61308.0, 61475.0, 61646.0, 61685.69157154275, 61695.38304616986, 64721.67639161868, 64833.67583162054, 64910.67544662341, 64991.35008649853, 65165.0, 16089.758653620205, 16387.180640967905, 16394.83605163933, 16397.754033689504, 16415.753763693556, 12943.223406595635, 13005.154664946747, 13034.80447793284, 13038.739225215442, 13048.673783155307, 15641.296141673653, 16849.831501685072, 17276.827231727773, 17369.0, 17798.733019004725, 6119.143319935212, 6132.6627035513475, 6169.907451388232, 6194.225721784777, 6235.064740288993, 1028.7685270814006, 1078.6440474643348, 1136.3127991768338, 1139.5441823270592, 1162.7267592115845, 34820.477692834625, 34865.82567087148, 35162.824185878904, 35218.47172292418, 35294.64705352914, 1178.4755783676183, 1206.166626727387, 1224.4489979509256, 1262.8547717012477, 1266.3478308671042, 17424.215910284067, 18109.63780724378, 18326.358577449726, 18382.540436488925, 18439.354622588413, 598458.0, 601415.0, 601720.0, 602584.0, 602659.0, 64832.0, 65004.0, 65083.0, 65200.67399662971, 65429.34570654233, 1313.8686131386976, 1419.8035191200158, 1461.0868207370393, 1465.0403985389587, 1466.0, 33696.663033369354, 35885.28229435449, 36384.818075909454, 36455.27089458143, 36607.45088823723, 54810.355689329386, 59165.704171478865, 60761.0, 60918.0, 65803.0, 560685.0, 565865.0, 566048.0, 566183.0, 566322.0, 28983.420331593672, 30250.54624180683, 30548.0, 32341.35317293594, 32547.34905301928, 52496.47503525069, 52862.0, 52942.47057529375, 53199.0, 53419.465805341446, 43998.0, 44738.3289250655, 44751.0, 46148.538514614425, 48337.51662483471, 45736.62790116302, 46397.76801115973, 46844.23596898805, 47121.293180603, 47235.527644723115, 45477.54522454733, 45572.54427455815, 45931.0, 47049.0, 47141.0, 37194.0, 39969.40045899372, 40338.596614033486, 40515.1896962065, 40775.0, 30454.390912182076, 30509.23726906845, 30696.0, 30773.538396924512, 30930.536041958938], "value": 21865.27049179578, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.30052956531745, 404.2702921227184, 411.5144129926686, 424.2448441773642, 441.14858323435755, 17400.39098631548, 20261.39215823524, 20344.59310813783, 20530.384088477353, 20536.178552857906, 19242.807571924277, 19425.805741942575, 19668.50828729279, 19811.60376792467, 20140.697889531668, 5389.218563308318, 5684.573656975723, 5936.0, 5945.524358051367, 5965.254343207099, 4056.736312139716, 5806.0, 5873.236479257691, 5962.910556341659, 6068.605540639866, 6088.330283668793, 6112.327643959183, 6123.387661233864, 6127.448529632332, 6130.0, 5909.556783241253, 5997.1304160896425, 6018.729157187937, 6052.243469566304, 6055.939440605582, 5419.187121931703, 5898.7640494380175, 5900.911486327708, 5905.261842269716, 5909.143174239711, 14130.646733831634, 14773.482928097465, 14797.482088126972, 14935.103893766409, 14941.626459338495, 2362.2795047510504, 2427.4295540547955, 2454.864982425966, 2505.974940250592, 2631.8815653295646, 11045.83431248532, 11098.500567474483, 11109.666709998713, 11139.164562657834, 14992.800575953897, 2054.116729806183, 2093.6126816538836, 2110.978890211109, 2141.2719675310314, 2237.641977283643, 75139.0, 82199.5890020546, 83258.58370708168, 83311.16688833156, 83902.58048709718, 11268.0, 11810.055195584333, 11862.762744745056, 11872.0, 11920.403979800974, 16769.413070542472, 16833.32666693343, 16850.662986740197, 16860.072696001593, 16868.746968795476, 8228.30059444942, 8440.493570385674, 8463.0, 8466.365022623328, 8531.488110713253, 8006.1993800619475, 8053.6375863086305, 8215.794605134919, 8274.0, 8293.502389856629, 4872.585830204473, 4929.802807887644, 4966.975165124151, 5023.723695196799, 5185.9740701297005, 2060.9690854637192, 2067.410787925434, 2078.8752674839557, 2102.6635738282107, 2107.3467225159943, 815.9930655534375, 918.6004088221512, 1026.9435181064964, 1046.3889177901003, 1078.2829418436652, 19145.329913452963, 19554.608907821763, 20076.196952122045, 20657.276995305092, 21018.894905525376, 990.3117333453325, 1035.1201478743158, 1039.7920415916847, 1060.1783617696346, 1071.2912903918238, 10988.340699558052, 11164.832527512093, 11752.765959574293, 11886.34625095628, 12311.322877241837, 382681.0, 386710.0, 388200.0, 545475.0, 557874.0, 89899.55050224708, 91054.54472727593, 94226.52015813372, 99930.5003474978, 108696.0, 1036.7926414717087, 1054.3568423261804, 1086.9076128529164, 1091.1816137896578, 1091.612477570467, 18452.446426607225, 18833.811661883752, 18980.810191897905, 19057.0, 19099.331523396613, 41728.58271417247, 43281.134377312905, 45278.0, 46666.0, 46685.29972050354, 59475.70262148661, 61017.0, 65870.34129658643, 67465.32534674786, 67663.3233667657, 5792.304923409218, 7711.26742959418, 8649.783755406164, 8837.425567338103, 9094.681686140953, 13085.672858178621, 13194.274314912387, 13271.66820829487, 13841.377138029015, 13870.4451821926, 11914.285142891455, 11953.940230298793, 12470.314132722424, 12481.500739970299, 12493.562725304571, 5576.944230557642, 9164.633414663338, 12052.819207711716, 12168.148229623841, 12321.630351089481, 12456.626301210978, 12488.625341239778, 12494.937525312314, 12540.247585144556, 12870.485180592672, 7988.6405111768945, 8674.436161649473, 8727.127287271222, 8821.60302786389, 8828.690995815115, 10795.244332896937, 11628.6511404658, 12161.270323780602, 12196.329201893981, 12302.0], "value": 9437.382578467785, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1538.3538913656266, 1579.2419638573485, 1766.8321509456605, 1845.9446216613505, 1887.8017808130137, 9285.907140928588, 10095.949520252389, 10113.39319640822, 10118.291719579633, 10125.797484050314, 8571.657133714643, 9265.629374825015, 9523.904760952388, 9551.856722149156, 9927.205823534117, 2476.5789815731323, 2518.181590982929, 2520.836145650536, 2521.7856482199036, 2522.583773677341, 2461.126300163443, 2502.962455563168, 2520.962185567218, 2523.5457617628817, 2524.3058159006237, 2425.63615457681, 2434.549608322457, 2463.3718401807514, 2471.9011239550396, 2473.0478765675252, 2420.8668523231304, 2435.4641978764644, 2435.8782060896974, 2442.1086303499274, 2442.7068751749816, 2397.0, 2413.9517209655883, 2424.5514579802793, 2425.151197081025, 2437.9512409751705, 9856.950715246378, 9954.70135895917, 9963.302568820185, 9972.15236704881, 9977.052180042885, 5416.133418653036, 5517.69652669103, 5518.117101263779, 5576.888462230732, 5597.412271711493, 3455.809930453849, 3574.9285014299567, 3642.326169658595, 3674.9448758268645, 3683.963160368389, 1148.253635137159, 1160.7388337623968, 1175.8882906123904, 1177.6937996120985, 1192.6362459449865, 13478.595642130751, 13492.865071349259, 13515.053946223768, 13518.053736238468, 13534.729305413935, 4799.808007679654, 4827.879303017382, 4969.925451118236, 4989.3264409305, 5069.366329208849, 11310.377929213972, 11317.547298108148, 11328.150388720878, 11329.0, 11331.093512518979, 5613.663180209201, 5634.7182640867795, 5651.886962260732, 5654.236678048411, 5662.405447428043, 4495.303227999665, 4553.954460455353, 5012.348394708664, 5093.643444958891, 5161.122609156452, 4399.076193999296, 4445.333200020023, 4455.509893911667, 4486.327050942385, 4530.750808705553, 1328.85382607915, 1366.1461586508433, 1382.315753701924, 1387.0429403711407, 1397.706481638847, 951.5052172870069, 1037.3412882819314, 1044.8223801953686, 1065.2117433099606, 1079.0989523747758, 6314.62112273265, 6747.730090796412, 6778.322167783281, 6787.864242715118, 6950.165980082423, 470.9317149013374, 499.03187815638273, 503.6046703337862, 506.3594552890596, 524.5279248676222, 4692.835750748707, 4698.412698412699, 4722.456917454537, 4739.336492890998, 4759.690620109751, 11913.166078374605, 12345.259284442962, 12370.938145309216, 12422.254664720145, 12425.502979880703, 10500.159987201005, 10828.945855270673, 10868.456577171268, 12305.93847030759, 12357.073219508393, 8749.212570868653, 8902.73291801247, 8933.508657023796, 8970.551472426352, 8974.28205743539, 6645.468362531083, 6657.788295402371, 6913.170419549686, 6968.651567421507, 6989.30106989297, 11108.222424430212, 11272.605458808903, 11293.774124517628, 11353.943230283796, 11401.657950261506, 11341.149413794163, 11401.885981140082, 11612.361320127136, 11709.414529273672, 11747.647570572897, 7824.100228473799, 8141.43009989295, 8250.174982501701, 8489.490630562186, 8608.870866936875, 9728.367656102331, 9811.607535698493, 9816.80366392654, 9819.509024548886, 9822.361546499455, 8544.829103417773, 8650.13498650155, 8693.0, 8834.293256539333, 8964.731058068268, 8313.542755148525, 8411.285040771358, 8411.285040771603, 8483.703070392507, 8615.956920215358, 8046.275835174628, 8058.879116813136, 8123.634436450492, 8126.959365203136, 8132.83734325322, 5515.806946756844, 5666.0, 5722.5135863450405, 5770.307563092288, 5799.188113664006, 5786.537077033743, 5859.619124756877, 5894.20428242199, 5903.49820265265, 5978.073398623221], "value": 4982.188556451317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.29247895574593, 344.6467370944781, 350.9646542699037, 351.99648003519974, 358.11724100093267, 5502.834914952553, 5572.275604171452, 5579.581531385147, 5609.691466969315, 5611.158326251064, 5508.834734957958, 5557.833265002057, 5577.609567330289, 5585.748641311141, 5598.524125449342, 3878.030492376906, 4150.273702102135, 4150.730202536825, 4317.445719540963, 4319.6760242981745, 4333.891652708676, 4336.3278691802825, 4341.761203133827, 4342.848000319989, 4347.41309923161, 4315.546867578906, 4335.937695264649, 4362.302031674933, 4363.752667376304, 4367.323064924942, 4081.163361510898, 4176.7076304658685, 4305.0, 4306.35404689296, 4335.219660461115, 3824.240849209367, 3830.578636349999, 3835.463035175078, 3838.4626152338697, 3851.364524853396, 5369.140937449989, 5379.085555455582, 5393.649412788195, 5396.1366181410785, 5432.538234250093, 4790.928136077961, 4935.2973224237585, 4949.282354058641, 4951.504849515056, 4986.825461108844, 5723.370429252779, 5731.656100633975, 5732.82801515951, 5740.167675687044, 5740.79443316904, 4233.993864356311, 4241.724287921275, 4257.787110644486, 4260.318349064166, 4260.510041345223, 5705.77176912923, 5949.940500594982, 5967.820965371003, 6016.1878146450545, 6038.667873266969, 4197.517285512145, 4206.684498662613, 4208.326667733148, 4223.619874211275, 4230.682698797603, 4299.161663475592, 4302.290122129876, 4309.870703878889, 4322.589354011364, 4329.502107257706, 4723.095296392694, 4864.343313652683, 4900.975495122501, 4934.9013019739405, 4960.677555958851, 5774.556360909773, 5798.507126894261, 5805.7387417566315, 5805.8548536286935, 5810.605454690928, 1471.1320321010655, 1477.4090363854543, 1480.9925950370396, 1482.3477669825243, 1487.300968544804, 937.6624415210518, 948.4309414351334, 948.9478078705738, 953.7853982853941, 973.2262851033446, 5029.371328583927, 5031.773570189277, 5044.243363495505, 5050.9494905051215, 5055.292259083732, 5275.657082289716, 5283.128283833124, 5286.60350473716, 5298.92051619226, 5319.414864364917, 4721.480637129913, 4964.801407943642, 4984.626153038536, 5071.8985620287385, 5079.720615366116, 2759.4756996170663, 2771.9168424947284, 2777.6389069420848, 2781.527140386099, 2816.690164081949, 5409.350877894678, 5436.374816896028, 5437.347518297751, 5467.398586155519, 5486.753096110685, 5150.047630210025, 5236.214567814782, 5282.022825777243, 5774.88450230993, 5913.2608423947, 6110.480609148183, 6148.569600128038, 6155.876882462415, 6158.568900177034, 6163.722632481459, 5972.0, 6009.287353104344, 6023.909641355295, 6028.788992385245, 6030.48740857032, 5644.491995720241, 5954.374790647093, 5977.880442391041, 5992.490638295793, 6001.2498437695285, 6176.382361763787, 6176.752929882754, 6176.7838125665385, 6179.0031834561105, 6192.328071420601, 4210.515790683988, 4260.339647354665, 4293.441852559147, 4306.913861722685, 4332.111917057059, 6005.579609427473, 6013.579049466495, 6021.698915054142, 6029.457348838626, 6031.517478601613, 5886.52907767369, 5895.41045895407, 5907.527397808251, 5925.970370148121, 5932.614380065282, 5686.0, 5834.620749651259, 5858.8242352729485, 5860.443257890664, 5869.943410186207, 5794.101914203306, 5805.796797112081, 5817.621854579439, 5818.912716309174, 5834.679092649775, 5613.298337707786, 5867.911981320369, 5912.645241285537, 5920.526357891272, 5929.733162007807, 5729.455701708331, 5732.308968854115, 5742.971285143548, 5772.191893135048, 5802.796902108406], "value": 4353.747592966234, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.2707563193126, 829.5603330234977, 883.9513826739529, 902.9819403611928, 906.8866391701038, 15347.693046139084, 16137.919310403446, 16169.514914552583, 16170.83829161711, 16183.757243641356, 16121.758173627404, 16455.34178632856, 16480.752788708178, 16483.58791030225, 16492.34030638776, 713.4185638704453, 853.4068822168596, 879.1120967822513, 881.2949640287776, 883.8630012348065, 885.2386947225414, 887.1084560017206, 887.7647423432795, 888.3470649072958, 888.3515034025165, 941.7928055827707, 942.3968660057569, 942.6606421688186, 943.3490891284991, 943.9528023598826, 759.7340930674275, 899.0829354058889, 921.3182245138617, 925.22281283722, 936.2182577547721, 683.733343995842, 684.0286792754301, 684.3567046975827, 684.9212340580847, 685.1777866560147, 15642.608934776608, 15660.216989150496, 15666.138362390064, 15666.608334791608, 15687.450939217188, 14469.92765036168, 14578.416863325454, 14710.0, 14822.110673359633, 14958.0, 12361.876381236163, 12421.565245216465, 12493.25040497573, 12605.369731513387, 12686.365681715877, 4210.410542524049, 4218.303979843353, 4297.484301883794, 4618.1687296286655, 4742.834000809989, 82774.58612706898, 83070.0, 86826.5658671709, 87274.56362718146, 88588.11411885863, 13033.608991730263, 13034.217946923214, 13093.083484156017, 13118.147320424141, 13167.736645267041, 24435.144769932966, 24647.260582182564, 24704.629430558554, 25042.123525676514, 25042.499150016898, 8870.86693699595, 8894.066123057115, 8915.687950921816, 9156.359054866229, 9172.541372931326, 4966.503349665077, 5064.873378165576, 5151.5621172200035, 5172.250023746536, 5175.378954525481, 4085.20338533983, 4269.573042695705, 4273.572642735701, 4273.893152671208, 4275.080857615628, 707.6391040569347, 707.7558242406361, 717.1573401253506, 725.3435640745134, 736.4292673178329, 679.218898266987, 685.3948232298147, 803.236924921327, 808.0626473290954, 837.9581020948929, 9774.31579789408, 10221.182305415548, 10222.182225422097, 10555.15558755313, 10580.841287380696, 784.8175647748321, 805.9479553903345, 811.4036183405193, 825.7522743177022, 830.8836762853206, 4989.90020199594, 5013.649044566845, 5027.120253955543, 5041.092603331435, 5054.974725126351, 127491.0, 132671.33664331617, 132811.0, 135363.0, 140464.0, 115569.4221528887, 115757.0, 116295.0, 118065.40967295108, 119904.0, 40121.1975760489, 76727.0, 77559.0, 78214.21785782183, 98714.50642746741, 9720.611175552898, 9879.110880020833, 9906.851397228904, 9923.255755818487, 10040.849387259052, 54244.0, 54726.17910731262, 55467.72266138643, 55592.44407556034, 56526.43473565376, 75484.24515754772, 78596.21403786118, 81843.5907820457, 82487.5875620642, 83335.583322083, 12953.287569183409, 13098.541551045668, 13216.669583260495, 13500.32498375097, 13601.79597306061, 14515.274236288355, 14670.77993830072, 14713.92643036778, 14817.110973341634, 15095.54713358601, 13895.444182232597, 14408.20754858493, 14563.708725825636, 14857.182854943081, 14863.40546378176, 12958.416871240628, 13014.80477792815, 13723.519676811664, 13768.586942391745, 14166.858331416553, 13012.739745205232, 13515.391807368494, 13517.59447216585, 13599.520862447358, 14043.722611472529, 11427.771444570895, 11556.94221528887, 11583.652490424964, 12034.879651203375, 12246.448909798903, 11373.715657108638, 11662.88337116618, 11684.298942063164, 12257.816132757838, 12321.876781232073], "value": 8129.80214100746, "unit": "score"}], "name": "dromaeo_css", "value": 7660.620024573656, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 109.0, 112.0, 109.0, 110.0, 109.0, 109.0, 112.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 121.0, 175.0, 116.0, 117.0, 144.0, 115.0, 149.0, 114.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 160.0, 159.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 109.0, 76.0, 77.0, 76.0, 75.0, 102.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 123.0, 83.0, 83.0, 84.0, 118.0, 82.0, 82.0, 119.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 144.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 49.0, 50.0, 49.0, 50.0, 51.0, 49.0, 49.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 59.0, 58.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 80.0, 72.0, 70.0, 71.0, 72.0, 72.0, 72.0, 76.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 116.0, 123.0, 115.0, 114.0, 116.0, 116.0, 147.0, 118.0], "value": 119.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 156.0, 153.0, 156.0, 157.0, 160.0, 157.0, 154.0, 155.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 65.0, 63.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1454.8000000000002, "alertThreshold": 2.0}]} 20:16:41 INFO - Return code: 0 20:16:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:16:41 INFO - # TBPL SUCCESS # 20:16:41 INFO - Running post-action listener: _resource_record_post_action 20:16:41 INFO - [mozharness: 2016-08-31 03:16:41.904145Z] Finished run-tests step (success) 20:16:41 INFO - Running post-run listener: _resource_record_post_run 20:16:41 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 328470528; Read time: 0; Write time: 253004 20:16:41 INFO - TinderboxPrint: CPU usage
13.0% 20:16:41 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:16:41 INFO - TinderboxPrint: I/O write bytes / time
328,470,528 / 253,004 20:16:41 INFO - TinderboxPrint: CPU idle
8,272.6 (87.0%) 20:16:41 INFO - TinderboxPrint: CPU user
1,185.6 (12.5%) 20:16:41 INFO - TinderboxPrint: Swap in / out
0 / 0 20:16:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79233024; Read time: 0; Write time: 102868 20:16:41 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249237504; Read time: 0; Write time: 150136 20:16:42 INFO - Running post-run listener: _upload_blobber_files 20:16:42 INFO - Blob upload gear active. 20:16:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:16:42 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 20:16:42 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'] 20:16:42 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 20:16:42 INFO - (blobuploader) - INFO - Open directory for files ... 20:16:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:16:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:16:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:43 INFO - (blobuploader) - INFO - Done attempting. 20:16:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:16:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:16:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:47 INFO - (blobuploader) - INFO - Done attempting. 20:16:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:16:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:16:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:48 INFO - (blobuploader) - INFO - Done attempting. 20:16:48 INFO - (blobuploader) - INFO - Iteration through files over. 20:16:48 INFO - Return code: 0 20:16:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:16:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:16:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f5da8aa1616109d5613537a2562e94a514859d578fed93d41958bdf72898e4d56d8fe82c6f0ee8e4fc6b8e53b013326ce4e7a459ca21b26870b977925de24a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfdc3184730c82249cc9c21d47b4218b5d379ada5c485981ce07048d01dc9beb1cb38bd87fb7a9721becdc92cdbe968805ebfd16e685b1c0b1b60bac0884d371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b2c880e85a30529dd1918a615c25ffb5eddf4d44bff0b5ea5772e482a51a777c819cb10c09dc4eb02688e76c73cfef9937a9228a6787814707895a97d9558e"} 20:16:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:16:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:16:48 INFO - Contents: 20:16:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6f5da8aa1616109d5613537a2562e94a514859d578fed93d41958bdf72898e4d56d8fe82c6f0ee8e4fc6b8e53b013326ce4e7a459ca21b26870b977925de24a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfdc3184730c82249cc9c21d47b4218b5d379ada5c485981ce07048d01dc9beb1cb38bd87fb7a9721becdc92cdbe968805ebfd16e685b1c0b1b60bac0884d371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b2c880e85a30529dd1918a615c25ffb5eddf4d44bff0b5ea5772e482a51a777c819cb10c09dc4eb02688e76c73cfef9937a9228a6787814707895a97d9558e"} 20:16:48 INFO - Running post-run listener: copy_logs_to_upload_dir 20:16:48 INFO - Copying logs to upload dir... 20:16:48 INFO - mkdir: /builds/slave/test/build/upload/logs 20:16:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.440094 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-30 20:16:47.901316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:16:47.905209) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 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/6f5da8aa1616109d5613537a2562e94a514859d578fed93d41958bdf72898e4d56d8fe82c6f0ee8e4fc6b8e53b013326ce4e7a459ca21b26870b977925de24a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfdc3184730c82249cc9c21d47b4218b5d379ada5c485981ce07048d01dc9beb1cb38bd87fb7a9721becdc92cdbe968805ebfd16e685b1c0b1b60bac0884d371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b2c880e85a30529dd1918a615c25ffb5eddf4d44bff0b5ea5772e482a51a777c819cb10c09dc4eb02688e76c73cfef9937a9228a6787814707895a97d9558e"} build_url:https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011388 build_url: 'https://queue.taskcluster.net/v1/task/XOKJgaiATNS6TZZa7xOkug/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/6f5da8aa1616109d5613537a2562e94a514859d578fed93d41958bdf72898e4d56d8fe82c6f0ee8e4fc6b8e53b013326ce4e7a459ca21b26870b977925de24a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bfdc3184730c82249cc9c21d47b4218b5d379ada5c485981ce07048d01dc9beb1cb38bd87fb7a9721becdc92cdbe968805ebfd16e685b1c0b1b60bac0884d371", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c5b2c880e85a30529dd1918a615c25ffb5eddf4d44bff0b5ea5772e482a51a777c819cb10c09dc4eb02688e76c73cfef9937a9228a6787814707895a97d9558e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:16:47.931610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:16:47.932012) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1472611677.650060-1142263581 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:16:48.020245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:16:48.020787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:16:48.021301) ========= ========= Total master_lag: 0.31 =========