builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1472897872.08 results: success (0) buildid: 20160902160050 builduid: b954e943d60047298c8c718863c8a0b0 revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.079341) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.079787) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.080148) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.094907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.095411) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-03 03:17:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-09-03 03:17:52 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.330743 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.441393) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.441713) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016041 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:52.492331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 03:17:52.492821) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --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 d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-03 03:17:52,642 truncating revision to first 12 chars 2016-09-03 03:17:52,642 Setting DEBUG logging. 2016-09-03 03:17:52,642 attempt 1/10 2016-09-03 03:17:52,642 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d35ac86c5271?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 03:17:53,521 unpacking tar archive at: mozilla-aurora-d35ac86c5271/testing/mozharness/ program finished with exit code 0 elapsedTime=1.221504 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 03:17:53.728491) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:53.729189) ========= script_repo_revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:53.730290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:53.730620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:53.740922) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-09-03 03:17:53.741181) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:17:53 INFO - MultiFileLogger online at 20160903 03:17:53 in /builds/slave/test 03:17:53 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 03:17:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:17:53 INFO - {'append_to_log': False, 03:17:53 INFO - 'base_work_dir': '/builds/slave/test', 03:17:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:17:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:17:53 INFO - 'branch': 'Mozilla-Aurora', 03:17:53 INFO - 'buildbot_json_path': 'buildprops.json', 03:17:53 INFO - 'config_files': ('talos/linux_config.py',), 03:17:53 INFO - 'default_actions': ('clobber', 03:17:53 INFO - 'read-buildbot-config', 03:17:53 INFO - 'download-and-extract', 03:17:53 INFO - 'populate-webroot', 03:17:53 INFO - 'create-virtualenv', 03:17:53 INFO - 'install', 03:17:53 INFO - 'run-tests'), 03:17:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:17:53 INFO - 'download_minidump_stackwalk': True, 03:17:53 INFO - 'download_symbols': 'ondemand', 03:17:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:17:53 INFO - 'tooltool.py': '/tools/tooltool.py', 03:17:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:17:53 INFO - '/tools/misc-python/virtualenv.py')}, 03:17:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:17:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:17:53 INFO - 'installer_path': 'installer.exe', 03:17:53 INFO - 'log_level': 'info', 03:17:53 INFO - 'log_name': 'talos', 03:17:53 INFO - 'log_to_console': True, 03:17:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:17:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:17:53 INFO - 'opt_config_files': (), 03:17:53 INFO - 'pip_index': False, 03:17:53 INFO - 'sps_profile': False, 03:17:53 INFO - 'sps_profile_interval': 0, 03:17:53 INFO - 'suite': 'dromaeojs', 03:17:53 INFO - 'system_bits': '32', 03:17:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:17:53 INFO - 'title': 'talos-linux64-ix-033', 03:17:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:17:53 INFO - 'use_talos_json': True, 03:17:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:17:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:17:53 INFO - 'work_dir': 'build'} 03:17:53 INFO - [mozharness: 2016-09-03 10:17:53.971415Z] Running clobber step. 03:17:53 INFO - Running pre-action listener: _resource_record_pre_action 03:17:53 INFO - Running main action method: clobber 03:17:53 INFO - rmtree: /builds/slave/test/build 03:17:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:17:54 INFO - Running post-action listener: _resource_record_post_action 03:17:54 INFO - [mozharness: 2016-09-03 10:17:54.406412Z] Finished clobber step (success) 03:17:54 INFO - [mozharness: 2016-09-03 10:17:54.406513Z] Running read-buildbot-config step. 03:17:54 INFO - Running pre-action listener: _resource_record_pre_action 03:17:54 INFO - Running main action method: read_buildbot_config 03:17:54 INFO - Using buildbot properties: 03:17:54 INFO - { 03:17:54 INFO - "project": "", 03:17:54 INFO - "product": "firefox", 03:17:54 INFO - "script_repo_revision": "production", 03:17:54 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:17:54 INFO - "repository": "", 03:17:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:17:54 INFO - "buildid": "20160902160050", 03:17:54 INFO - "pgo_build": "True", 03:17:54 INFO - "basedir": "/builds/slave/test", 03:17:54 INFO - "buildnumber": 732, 03:17:54 INFO - "slavename": "talos-linux64-ix-033", 03:17:54 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:17:54 INFO - "platform": "ubuntu64_hw", 03:17:54 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:17:54 INFO - "branch": "mozilla-aurora", 03:17:54 INFO - "repo_path": "releases/mozilla-aurora", 03:17:54 INFO - "revision": "d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd", 03:17:54 INFO - "stage_platform": "linux64-pgo", 03:17:54 INFO - "builduid": "b954e943d60047298c8c718863c8a0b0", 03:17:54 INFO - "slavebuilddir": "test" 03:17:54 INFO - } 03:17:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:17:54 INFO - Running post-action listener: _resource_record_post_action 03:17:54 INFO - [mozharness: 2016-09-03 10:17:54.408946Z] Finished read-buildbot-config step (success) 03:17:54 INFO - [mozharness: 2016-09-03 10:17:54.409035Z] Running download-and-extract step. 03:17:54 INFO - Running pre-action listener: _resource_record_pre_action 03:17:54 INFO - Running main action method: download_and_extract 03:17:54 INFO - mkdir: /builds/slave/test/build/tests 03:17:54 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')]} 03:17:54 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:17:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:17:54 INFO - retry: Failed, sleeping 30 seconds before retrying 03:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:18:24 INFO - retry: Failed, sleeping 60 seconds before retrying 03:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:19:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:19:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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! 03:19:24 INFO - Caught exception: HTTP Error 404: Not Found 03:19:24 INFO - Caught exception: HTTP Error 404: Not Found 03:19:24 INFO - Caught exception: HTTP Error 404: Not Found 03:19:24 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:19:24 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:25 INFO - Downloaded 1361 bytes. 03:19:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:19:25 INFO - Using the following test package requirements: 03:19:25 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:19:25 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:19:25 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:19:25 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'jsshell-linux-x86_64.zip'], 03:19:25 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:19:25 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:19:25 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:19:25 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:19:25 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:19:25 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:19:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:19:25 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:19:25 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:19:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:19:25 INFO - retry: Failed, sleeping 30 seconds before retrying 03:19:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:19:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:19:55 INFO - retry: Failed, sleeping 60 seconds before retrying 03:20:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:20:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:20:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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! 03:20:55 INFO - Caught exception: HTTP Error 404: Not Found 03:20:55 INFO - Caught exception: HTTP Error 404: Not Found 03:20:55 INFO - Caught exception: HTTP Error 404: Not Found 03:20:55 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:55 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:20:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:20:55 INFO - Downloaded 21839364 bytes. 03:20:55 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'] 03:20:55 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 03:20:57 INFO - Return code: 0 03:20:57 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 03:20:57 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')]} 03:20:57 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:20:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:20:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:20:57 INFO - retry: Failed, sleeping 30 seconds before retrying 03:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:21:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:21:27 INFO - retry: Failed, sleeping 60 seconds before retrying 03:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:22:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:22:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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! 03:22:27 INFO - Caught exception: HTTP Error 404: Not Found 03:22:27 INFO - Caught exception: HTTP Error 404: Not Found 03:22:27 INFO - Caught exception: HTTP Error 404: Not Found 03:22:27 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:22:27 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:28 INFO - Downloaded 21839364 bytes. 03:22:28 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'] 03:22:28 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 03:22:29 INFO - Return code: 0 03:22:29 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')]} 03:22:29 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:22:29 INFO - retry: Failed, sleeping 30 seconds before retrying 03:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:22:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:22:59 INFO - retry: Failed, sleeping 60 seconds before retrying 03:23:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:23:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:23:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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! 03:23:59 INFO - Caught exception: HTTP Error 404: Not Found 03:23:59 INFO - Caught exception: HTTP Error 404: Not Found 03:23:59 INFO - Caught exception: HTTP Error 404: Not Found 03:23:59 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:59 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:23:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:24:00 INFO - Downloaded 14413740 bytes. 03:24:00 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'] 03:24:00 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 03:24:00 INFO - Return code: 0 03:24:00 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')]} 03:24:00 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:24:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:24:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:24:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:24:00 INFO - retry: Failed, sleeping 30 seconds before retrying 03:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:24:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:24:30 INFO - retry: Failed, sleeping 60 seconds before retrying 03:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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 03:25:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:25:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:25:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/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! 03:25:30 INFO - Caught exception: HTTP Error 404: Not Found 03:25:30 INFO - Caught exception: HTTP Error 404: Not Found 03:25:30 INFO - Caught exception: HTTP Error 404: Not Found 03:25:30 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:25:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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 03:25:31 INFO - Downloaded 63899078 bytes. 03:25:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:25:31 INFO - mkdir: /builds/slave/test/properties 03:25:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:25:31 INFO - Writing to file /builds/slave/test/properties/build_url 03:25:31 INFO - Contents: 03:25:31 INFO - build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:25:32 INFO - Running post-action listener: _resource_record_post_action 03:25:32 INFO - Running post-action listener: set_extra_try_arguments 03:25:32 INFO - [mozharness: 2016-09-03 10:25:32.530360Z] Finished download-and-extract step (success) 03:25:32 INFO - [mozharness: 2016-09-03 10:25:32.530593Z] Running populate-webroot step. 03:25:32 INFO - Running pre-action listener: _resource_record_pre_action 03:25:32 INFO - Running main action method: populate_webroot 03:25:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:25:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:25:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:25:32 INFO - '--tppagecycles', 03:25:32 INFO - '7'], 03:25:32 INFO - 'tests': ['tsvgm']}}, 03:25:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:25:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:25:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:25:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:25:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:25:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['damp', 'tps']}, 03:25:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'tests': ['damp', 'tps']}, 03:25:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['dromaeo_dom']}, 03:25:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:25:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['basic_compositor_video']}, 03:25:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:25:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'other-e10s': {'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['a11yr', 03:25:32 INFO - 'ts_paint', 03:25:32 INFO - 'tpaint', 03:25:32 INFO - 'sessionrestore', 03:25:32 INFO - 'sessionrestore_no_auto_restore', 03:25:32 INFO - 'tabpaint']}, 03:25:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['tsvgx', 03:25:32 INFO - 'tsvgr_opacity', 03:25:32 INFO - 'tart', 03:25:32 INFO - 'tscrollx', 03:25:32 INFO - 'cart']}, 03:25:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:25:32 INFO - 'tsvgr_opacity', 03:25:32 INFO - 'tart', 03:25:32 INFO - 'tscrollx', 03:25:32 INFO - 'cart']}, 03:25:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'talos_options': ['--disable-e10s'], 03:25:32 INFO - 'tests': ['tp5o']}, 03:25:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'tests': ['tp5o']}, 03:25:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'talos_options': ['--disable-e10s', 03:25:32 INFO - '--xperf_path', 03:25:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:32 INFO - 'tests': ['tp5n']}, 03:25:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:25:32 INFO - 'talos_options': ['--xperf_path', 03:25:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:25:32 INFO - 'tests': ['tp5n']}}, 03:25:32 INFO - 'talos.zip': {'path': '', 03:25:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:25:32 INFO - Running post-action listener: _resource_record_post_action 03:25:32 INFO - [mozharness: 2016-09-03 10:25:32.548306Z] Finished populate-webroot step (success) 03:25:32 INFO - [mozharness: 2016-09-03 10:25:32.548395Z] Running create-virtualenv step. 03:25:32 INFO - Running pre-action listener: _resource_record_pre_action 03:25:32 INFO - Running main action method: create_virtualenv 03:25:32 INFO - Creating virtualenv /builds/slave/test/build/venv 03:25:32 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 03:25:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:25:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:25:32 INFO - Using real prefix '/usr' 03:25:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:25:33 INFO - Installing distribute.............................................................................................................................................................................................done. 03:25:35 INFO - Installing pip.................done. 03:25:35 INFO - Return code: 0 03:25:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:25:35 INFO - Using env: {'DISPLAY': ':0', 03:25:35 INFO - 'HOME': '/home/cltbld', 03:25:35 INFO - 'LANG': 'en_US.UTF-8', 03:25:35 INFO - 'LANGUAGE': 'en_US:en', 03:25:35 INFO - 'LOGNAME': 'cltbld', 03:25:35 INFO - 'MAIL': '/var/mail/cltbld', 03:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:35 INFO - 'MOZ_NO_REMOTE': '1', 03:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:35 INFO - 'NO_EM_RESTART': '1', 03:25:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:35 INFO - 'PWD': '/builds/slave/test', 03:25:35 INFO - 'SHELL': '/bin/bash', 03:25:35 INFO - 'SHLVL': '1', 03:25:35 INFO - 'TERM': 'linux', 03:25:35 INFO - 'TMOUT': '86400', 03:25:35 INFO - 'USER': 'cltbld', 03:25:35 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:35 INFO - '_': '/tools/buildbot/bin/python'} 03:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:25:35 INFO - Cleaning up... 03:25:35 INFO - Return code: 0 03:25:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:25:35 INFO - Using env: {'DISPLAY': ':0', 03:25:35 INFO - 'HOME': '/home/cltbld', 03:25:35 INFO - 'LANG': 'en_US.UTF-8', 03:25:35 INFO - 'LANGUAGE': 'en_US:en', 03:25:35 INFO - 'LOGNAME': 'cltbld', 03:25:35 INFO - 'MAIL': '/var/mail/cltbld', 03:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:35 INFO - 'MOZ_NO_REMOTE': '1', 03:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:35 INFO - 'NO_EM_RESTART': '1', 03:25:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:35 INFO - 'PWD': '/builds/slave/test', 03:25:35 INFO - 'SHELL': '/bin/bash', 03:25:35 INFO - 'SHLVL': '1', 03:25:35 INFO - 'TERM': 'linux', 03:25:35 INFO - 'TMOUT': '86400', 03:25:35 INFO - 'USER': 'cltbld', 03:25:35 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:35 INFO - '_': '/tools/buildbot/bin/python'} 03:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:35 INFO - Downloading/unpacking psutil>=3.1.1 03:25:35 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 03:25:35 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 03:25:35 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 03:25:35 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 03:25:35 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 03:25:35 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 03:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:25:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:37 INFO - Installing collected packages: psutil 03:25:37 INFO - Running setup.py install for psutil 03:25:37 INFO - building 'psutil._psutil_linux' extension 03:25:37 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 03:25:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:25:37 INFO - building 'psutil._psutil_posix' extension 03:25:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:25:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:25:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:38 INFO - Successfully installed psutil 03:25:38 INFO - Cleaning up... 03:25:38 INFO - Return code: 0 03:25:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:25:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:25:38 INFO - Using env: {'DISPLAY': ':0', 03:25:38 INFO - 'HOME': '/home/cltbld', 03:25:38 INFO - 'LANG': 'en_US.UTF-8', 03:25:38 INFO - 'LANGUAGE': 'en_US:en', 03:25:38 INFO - 'LOGNAME': 'cltbld', 03:25:38 INFO - 'MAIL': '/var/mail/cltbld', 03:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:38 INFO - 'MOZ_NO_REMOTE': '1', 03:25:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:38 INFO - 'NO_EM_RESTART': '1', 03:25:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:38 INFO - 'PWD': '/builds/slave/test', 03:25:38 INFO - 'SHELL': '/bin/bash', 03:25:38 INFO - 'SHLVL': '1', 03:25:38 INFO - 'TERM': 'linux', 03:25:38 INFO - 'TMOUT': '86400', 03:25:38 INFO - 'USER': 'cltbld', 03:25:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:38 INFO - '_': '/tools/buildbot/bin/python'} 03:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:25:40 INFO - Installing collected packages: mozsystemmonitor 03:25:40 INFO - Running setup.py install for mozsystemmonitor 03:25:40 INFO - Successfully installed mozsystemmonitor 03:25:40 INFO - Cleaning up... 03:25:40 INFO - Return code: 0 03:25:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:25:40 INFO - Using env: {'DISPLAY': ':0', 03:25:40 INFO - 'HOME': '/home/cltbld', 03:25:40 INFO - 'LANG': 'en_US.UTF-8', 03:25:40 INFO - 'LANGUAGE': 'en_US:en', 03:25:40 INFO - 'LOGNAME': 'cltbld', 03:25:40 INFO - 'MAIL': '/var/mail/cltbld', 03:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:40 INFO - 'MOZ_NO_REMOTE': '1', 03:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:40 INFO - 'NO_EM_RESTART': '1', 03:25:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:40 INFO - 'PWD': '/builds/slave/test', 03:25:40 INFO - 'SHELL': '/bin/bash', 03:25:40 INFO - 'SHLVL': '1', 03:25:40 INFO - 'TERM': 'linux', 03:25:40 INFO - 'TMOUT': '86400', 03:25:40 INFO - 'USER': 'cltbld', 03:25:40 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:40 INFO - '_': '/tools/buildbot/bin/python'} 03:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:40 INFO - Downloading/unpacking jsonschema==2.5.1 03:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:25:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:25: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 03:25: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 03:25: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 03:25: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 03:25: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 03:25: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 03:25:42 INFO - Downloading functools32-3.2.3-2.tar.gz 03:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:25:42 INFO - warning: no files found matching '*.txt' 03:25:42 INFO - no previously-included directories found matching 'build' 03:25:42 INFO - no previously-included directories found matching 'dist' 03:25:42 INFO - no previously-included directories found matching '.git*' 03:25:42 INFO - Installing collected packages: jsonschema, functools32 03:25:42 INFO - Running setup.py install for functools32 03:25:42 INFO - warning: no files found matching '*.txt' 03:25:42 INFO - no previously-included directories found matching 'build' 03:25:42 INFO - no previously-included directories found matching 'dist' 03:25:42 INFO - no previously-included directories found matching '.git*' 03:25:42 INFO - Successfully installed jsonschema functools32 03:25:42 INFO - Cleaning up... 03:25:42 INFO - Return code: 0 03:25:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:25:42 INFO - Using env: {'DISPLAY': ':0', 03:25:42 INFO - 'HOME': '/home/cltbld', 03:25:42 INFO - 'LANG': 'en_US.UTF-8', 03:25:42 INFO - 'LANGUAGE': 'en_US:en', 03:25:42 INFO - 'LOGNAME': 'cltbld', 03:25:42 INFO - 'MAIL': '/var/mail/cltbld', 03:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:42 INFO - 'MOZ_NO_REMOTE': '1', 03:25:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:42 INFO - 'NO_EM_RESTART': '1', 03:25:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:42 INFO - 'PWD': '/builds/slave/test', 03:25:42 INFO - 'SHELL': '/bin/bash', 03:25:42 INFO - 'SHLVL': '1', 03:25:42 INFO - 'TERM': 'linux', 03:25:42 INFO - 'TMOUT': '86400', 03:25:42 INFO - 'USER': 'cltbld', 03:25:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:42 INFO - '_': '/tools/buildbot/bin/python'} 03:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:25:43 INFO - Cleaning up... 03:25:43 INFO - Return code: 0 03:25:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:25:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:25:43 INFO - Using env: {'DISPLAY': ':0', 03:25:43 INFO - 'HOME': '/home/cltbld', 03:25:43 INFO - 'LANG': 'en_US.UTF-8', 03:25:43 INFO - 'LANGUAGE': 'en_US:en', 03:25:43 INFO - 'LOGNAME': 'cltbld', 03:25:43 INFO - 'MAIL': '/var/mail/cltbld', 03:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:43 INFO - 'MOZ_NO_REMOTE': '1', 03:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:43 INFO - 'NO_EM_RESTART': '1', 03:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:43 INFO - 'PWD': '/builds/slave/test', 03:25:43 INFO - 'SHELL': '/bin/bash', 03:25:43 INFO - 'SHLVL': '1', 03:25:43 INFO - 'TERM': 'linux', 03:25:43 INFO - 'TMOUT': '86400', 03:25:43 INFO - 'USER': 'cltbld', 03:25:43 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:43 INFO - '_': '/tools/buildbot/bin/python'} 03:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:43 INFO - Downloading/unpacking blobuploader==1.2.4 03:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:45 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:45 INFO - Downloading docopt-0.6.1.tar.gz 03:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:46 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:46 INFO - Running setup.py install for blobuploader 03:25:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:46 INFO - Running setup.py install for requests 03:25:46 INFO - Running setup.py install for docopt 03:25:46 INFO - Successfully installed blobuploader requests docopt 03:25:46 INFO - Cleaning up... 03:25:46 INFO - Return code: 0 03:25:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:46 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 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:46 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 03:25:46 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 03:25:46 INFO - Using env: {'DISPLAY': ':0', 03:25:46 INFO - 'HOME': '/home/cltbld', 03:25:46 INFO - 'LANG': 'en_US.UTF-8', 03:25:46 INFO - 'LANGUAGE': 'en_US:en', 03:25:46 INFO - 'LOGNAME': 'cltbld', 03:25:46 INFO - 'MAIL': '/var/mail/cltbld', 03:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:46 INFO - 'MOZ_NO_REMOTE': '1', 03:25:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:46 INFO - 'NO_EM_RESTART': '1', 03:25:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:46 INFO - 'PWD': '/builds/slave/test', 03:25:46 INFO - 'SHELL': '/bin/bash', 03:25:46 INFO - 'SHLVL': '1', 03:25:46 INFO - 'TERM': 'linux', 03:25:46 INFO - 'TMOUT': '86400', 03:25:46 INFO - 'USER': 'cltbld', 03:25:46 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:46 INFO - '_': '/tools/buildbot/bin/python'} 03:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:46 INFO - Running setup.py (path:/tmp/pip-rFcqJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:46 INFO - Running setup.py (path:/tmp/pip-jZmU_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:47 INFO - Running setup.py (path:/tmp/pip-metkk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:47 INFO - Running setup.py (path:/tmp/pip-soBMkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:47 INFO - Running setup.py (path:/tmp/pip-bQ7sbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:47 INFO - Running setup.py (path:/tmp/pip-3qBZG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:47 INFO - Running setup.py (path:/tmp/pip-9BVWoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:47 INFO - Running setup.py (path:/tmp/pip-fJB9cN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:47 INFO - Running setup.py (path:/tmp/pip-Ng6dEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:47 INFO - Running setup.py (path:/tmp/pip-6Lqe7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:47 INFO - Running setup.py (path:/tmp/pip-y8QecK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:47 INFO - Running setup.py (path:/tmp/pip-_kiSsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:47 INFO - Running setup.py (path:/tmp/pip-1EFHSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:47 INFO - Running setup.py (path:/tmp/pip-DF8998-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:47 INFO - Running setup.py (path:/tmp/pip-PkaRtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:47 INFO - Running setup.py (path:/tmp/pip-tBJWUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:48 INFO - Running setup.py (path:/tmp/pip-jzB7sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:48 INFO - Running setup.py install for manifestparser 03:25:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Running setup.py install for mozcrash 03:25:48 INFO - Running setup.py install for mozdebug 03:25:48 INFO - Running setup.py install for mozdevice 03:25:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Running setup.py install for mozfile 03:25:48 INFO - Running setup.py install for mozhttpd 03:25:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Running setup.py install for mozinfo 03:25:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Running setup.py install for mozInstall 03:25:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:48 INFO - Running setup.py install for mozleak 03:25:49 INFO - Running setup.py install for mozlog 03:25:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Running setup.py install for moznetwork 03:25:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Running setup.py install for mozprocess 03:25:49 INFO - Running setup.py install for mozprofile 03:25:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Running setup.py install for mozrunner 03:25:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Running setup.py install for mozscreenshot 03:25:49 INFO - Running setup.py install for moztest 03:25:49 INFO - Running setup.py install for mozversion 03:25:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:25:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:25:49 INFO - Cleaning up... 03:25:49 INFO - Return code: 0 03:25:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:25:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:25:49 INFO - Using env: {'DISPLAY': ':0', 03:25:49 INFO - 'HOME': '/home/cltbld', 03:25:49 INFO - 'LANG': 'en_US.UTF-8', 03:25:49 INFO - 'LANGUAGE': 'en_US:en', 03:25:49 INFO - 'LOGNAME': 'cltbld', 03:25:49 INFO - 'MAIL': '/var/mail/cltbld', 03:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:49 INFO - 'MOZ_NO_REMOTE': '1', 03:25:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:49 INFO - 'NO_EM_RESTART': '1', 03:25:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:49 INFO - 'PWD': '/builds/slave/test', 03:25:49 INFO - 'SHELL': '/bin/bash', 03:25:49 INFO - 'SHLVL': '1', 03:25:49 INFO - 'TERM': 'linux', 03:25:49 INFO - 'TMOUT': '86400', 03:25:49 INFO - 'USER': 'cltbld', 03:25:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:49 INFO - '_': '/tools/buildbot/bin/python'} 03:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:50 INFO - Running setup.py (path:/tmp/pip-k_ftSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:50 INFO - Running setup.py (path:/tmp/pip-JHBSw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:50 INFO - Running setup.py (path:/tmp/pip-giGELS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:50 INFO - Running setup.py (path:/tmp/pip-o1oHGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:50 INFO - Running setup.py (path:/tmp/pip-tf0j03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:50 INFO - Running setup.py (path:/tmp/pip-wszsUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:50 INFO - Running setup.py (path:/tmp/pip-VpBkDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:50 INFO - Running setup.py (path:/tmp/pip-ROa47F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:50 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)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:50 INFO - Running setup.py (path:/tmp/pip-wfJmmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:50 INFO - Running setup.py (path:/tmp/pip-U_xjGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:50 INFO - Running setup.py (path:/tmp/pip-vNpX39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:50 INFO - Running setup.py (path:/tmp/pip-XU7hOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:25:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:51 INFO - Running setup.py (path:/tmp/pip-Kmuxgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:51 INFO - Running setup.py (path:/tmp/pip-MLfW9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:51 INFO - Running setup.py (path:/tmp/pip-Y3fJ_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:51 INFO - Running setup.py (path:/tmp/pip-PkDqZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:25:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:51 INFO - Running setup.py (path:/tmp/pip-FpmkDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:53 INFO - Downloading blessings-1.6.tar.gz 03:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:25:53 INFO - Installing collected packages: blessings 03:25:53 INFO - Running setup.py install for blessings 03:25:53 INFO - Successfully installed blessings 03:25:53 INFO - Cleaning up... 03:25:53 INFO - Return code: 0 03:25:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:53 INFO - Reading from file tmpfile_stdout 03:25:53 INFO - Current package versions: 03:25:53 INFO - argparse == 1.2.1 03:25:53 INFO - blessings == 1.6 03:25:53 INFO - blobuploader == 1.2.4 03:25:53 INFO - docopt == 0.6.1 03:25:53 INFO - functools32 == 3.2.3-2 03:25:53 INFO - jsonschema == 2.5.1 03:25:53 INFO - manifestparser == 1.1 03:25:53 INFO - mozInstall == 1.12 03:25:53 INFO - mozcrash == 0.17 03:25:53 INFO - mozdebug == 0.1 03:25:53 INFO - mozdevice == 0.48 03:25:53 INFO - mozfile == 1.2 03:25:53 INFO - mozhttpd == 0.7 03:25:53 INFO - mozinfo == 0.9 03:25:53 INFO - mozleak == 0.1 03:25:53 INFO - mozlog == 3.3 03:25:53 INFO - moznetwork == 0.27 03:25:53 INFO - mozprocess == 0.23 03:25:53 INFO - mozprofile == 0.28 03:25:53 INFO - mozrunner == 6.12 03:25:53 INFO - mozscreenshot == 0.1 03:25:53 INFO - mozsystemmonitor == 0.3 03:25:53 INFO - moztest == 0.7 03:25:53 INFO - mozversion == 1.4 03:25:53 INFO - psutil == 3.1.1 03:25:53 INFO - requests == 1.2.3 03:25:53 INFO - wsgiref == 0.1.2 03:25:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:53 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 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:53 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 03:25:53 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 03:25:53 INFO - Using env: {'DISPLAY': ':0', 03:25:53 INFO - 'HOME': '/home/cltbld', 03:25:53 INFO - 'LANG': 'en_US.UTF-8', 03:25:53 INFO - 'LANGUAGE': 'en_US:en', 03:25:53 INFO - 'LOGNAME': 'cltbld', 03:25:53 INFO - 'MAIL': '/var/mail/cltbld', 03:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:53 INFO - 'MOZ_NO_REMOTE': '1', 03:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:53 INFO - 'NO_EM_RESTART': '1', 03:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:53 INFO - 'PWD': '/builds/slave/test', 03:25:53 INFO - 'SHELL': '/bin/bash', 03:25:53 INFO - 'SHLVL': '1', 03:25:53 INFO - 'TERM': 'linux', 03:25:53 INFO - 'TMOUT': '86400', 03:25:53 INFO - 'USER': 'cltbld', 03:25:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:53 INFO - '_': '/tools/buildbot/bin/python'} 03:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:25:53 INFO - Cleaning up... 03:25:53 INFO - Return code: 0 03:25:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x123a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', '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 03:25:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:25:53 INFO - Using env: {'DISPLAY': ':0', 03:25:53 INFO - 'HOME': '/home/cltbld', 03:25:53 INFO - 'LANG': 'en_US.UTF-8', 03:25:53 INFO - 'LANGUAGE': 'en_US:en', 03:25:53 INFO - 'LOGNAME': 'cltbld', 03:25:53 INFO - 'MAIL': '/var/mail/cltbld', 03:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:53 INFO - 'MOZ_NO_REMOTE': '1', 03:25:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:53 INFO - 'NO_EM_RESTART': '1', 03:25:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:53 INFO - 'PWD': '/builds/slave/test', 03:25:53 INFO - 'SHELL': '/bin/bash', 03:25:53 INFO - 'SHLVL': '1', 03:25:53 INFO - 'TERM': 'linux', 03:25:53 INFO - 'TMOUT': '86400', 03:25:53 INFO - 'USER': 'cltbld', 03:25:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:53 INFO - '_': '/tools/buildbot/bin/python'} 03:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:25:54 INFO - Cleaning up... 03:25:54 INFO - Return code: 0 03:25:54 INFO - Running post-action listener: _resource_record_post_action 03:25:54 INFO - Running post-action listener: _start_resource_monitoring 03:25:54 INFO - Starting resource monitoring. 03:25:54 INFO - [mozharness: 2016-09-03 10:25:54.186206Z] Finished create-virtualenv step (success) 03:25:54 INFO - [mozharness: 2016-09-03 10:25:54.186686Z] Running install step. 03:25:54 INFO - Running pre-action listener: _resource_record_pre_action 03:25:54 INFO - Running main action method: install 03:25:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:54 INFO - Reading from file tmpfile_stdout 03:25:54 INFO - Detecting whether we're running mozinstall >=1.0... 03:25:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:25:54 INFO - Reading from file tmpfile_stdout 03:25:54 INFO - Output received: 03:25:54 INFO - Usage: mozinstall [options] installer 03:25:54 INFO - Options: 03:25:54 INFO - -h, --help show this help message and exit 03:25:54 INFO - -d DEST, --destination=DEST 03:25:54 INFO - Directory to install application into. [default: 03:25:54 INFO - "/builds/slave/test"] 03:25:54 INFO - --app=APP Application being installed. [default: firefox] 03:25:54 INFO - mkdir: /builds/slave/test/build/application 03:25:54 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'] 03:25:54 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 03:26:12 INFO - Reading from file tmpfile_stdout 03:26:12 INFO - Output received: 03:26:12 INFO - /builds/slave/test/build/application/firefox/firefox 03:26:12 INFO - Running post-action listener: _resource_record_post_action 03:26:12 INFO - [mozharness: 2016-09-03 10:26:12.021950Z] Finished install step (success) 03:26:12 INFO - [mozharness: 2016-09-03 10:26:12.022224Z] Running run-tests step. 03:26:12 INFO - Running pre-action listener: _resource_record_pre_action 03:26:12 INFO - Running main action method: run_tests 03:26:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:26:12 INFO - Python 2.7.3 03:26:12 INFO - Return code: 0 03:26:12 INFO - grabbing minidump binary from tooltool 03:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:26:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14f93b0>, '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 0x14fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14fa740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:26:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:26:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:26:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:26:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:26:12 INFO - Return code: 0 03:26:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:26:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:26:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:26:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:26:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:26:12 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 03:26:12 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 03:26:12 INFO - Using env: {'DISPLAY': ':0', 03:26:12 INFO - 'HOME': '/home/cltbld', 03:26:12 INFO - 'LANG': 'en_US.UTF-8', 03:26:12 INFO - 'LANGUAGE': 'en_US:en', 03:26:12 INFO - 'LOGNAME': 'cltbld', 03:26:12 INFO - 'MAIL': '/var/mail/cltbld', 03:26:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:26:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:12 INFO - 'MOZ_NO_REMOTE': '1', 03:26:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:26:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:12 INFO - 'NO_EM_RESTART': '1', 03:26:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:12 INFO - 'PWD': '/builds/slave/test', 03:26:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:26:12 INFO - 'SHELL': '/bin/bash', 03:26:12 INFO - 'SHLVL': '1', 03:26:12 INFO - 'TERM': 'linux', 03:26:12 INFO - 'TMOUT': '86400', 03:26:12 INFO - 'USER': 'cltbld', 03:26:12 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059', 03:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:12 INFO - '_': '/tools/buildbot/bin/python'} 03:26:12 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 03:26:12 INFO - mozversion application_buildid: 20160902160050 03:26:12 INFO - mozversion application_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:26:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:26:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:26:12 INFO - mozversion application_name: Firefox 03:26:12 INFO - mozversion application_remotingname: firefox-dev 03:26:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:26:12 INFO - mozversion application_vendor: Mozilla 03:26:12 INFO - mozversion application_version: 50.0a2 03:26:12 INFO - mozversion platform_buildid: 20160902160050 03:26:12 INFO - mozversion platform_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:26:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:26:12 INFO - mozversion platform_version: 50.0a2 03:26:12 INFO - using testdate: 1472898372 03:26:12 INFO - actual date: 1472898372 03:26:12 INFO - starting webserver on 'localhost:41386' 03:26:12 INFO - SUITE-START | Running 2 tests 03:26:12 INFO - TEST-START | dromaeo_css 03:26:12 INFO - operating with platform_type : linux_ 03:26:12 INFO - Initialising browser for dromaeo_css test... 03:26:12 INFO - TEST-INFO | started process 5505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJbmjxV/profile http://localhost:41386/getInfo.html) 03:26:16 INFO - PROCESS | 5505 | __metrics Screen width/height:1600/1200 03:26:16 INFO - PROCESS | 5505 | colorDepth:24 03:26:16 INFO - PROCESS | 5505 | Browser inner width/height: 1024/697 03:26:16 INFO - PROCESS | 5505 | __metrics 03:26:18 INFO - PROCESS | 5505 | 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" 03:26:18 INFO - PROCESS | 5505 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:26:19 INFO - TEST-INFO | 5505: exit 0 03:26:19 INFO - Browser initialized. 03:26:19 INFO - Running cycle 1/1 for dromaeo_css test... 03:26:19 INFO - TEST-INFO | started process 5597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJbmjxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:30 INFO - PROCESS | 5597 | RSS: Main: 144965632 03:26:30 INFO - PROCESS | 5597 | 03:27:21 INFO - PROCESS | 5597 | 1472898441899 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] 03:29:31 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41386/tests/dromaeo/cssquery-ext.html) 03:29:32 INFO - PROCESS | 5597 | RSS: Main: 277016576 03:29:32 INFO - PROCESS | 5597 | 03:32:34 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-ext.html (next: http://localhost:41386/tests/dromaeo/cssquery-jquery.html) 03:32:35 INFO - PROCESS | 5597 | RSS: Main: 226963456 03:32:35 INFO - PROCESS | 5597 | 03:35:37 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41386/tests/dromaeo/cssquery-mootools.html) 03:35:38 INFO - PROCESS | 5597 | RSS: Main: 173621248 03:35:38 INFO - PROCESS | 5597 | 03:38:40 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41386/tests/dromaeo/cssquery-prototype.html) 03:38:40 INFO - PROCESS | 5597 | RSS: Main: 174891008 03:38:40 INFO - PROCESS | 5597 | 03:41:43 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41386/tests/dromaeo/cssquery-yui.html) 03:41:43 INFO - PROCESS | 5597 | RSS: Main: 175882240 03:41:43 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | Cycle 1(1): loaded http://localhost:41386/tests/dromaeo/cssquery-yui.html (next: http://localhost:41386/tests/dromaeo/cssquery-dojo.html) 03:44:46 INFO - PROCESS | 5597 | RSS: Main: 255406080 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | __start_tp_report 03:44:46 INFO - PROCESS | 5597 | _x_x_mozilla_page_load 03:44:46 INFO - PROCESS | 5597 | _x_x_mozilla_page_load_details 03:44:46 INFO - PROCESS | 5597 | |i|pagename|runs| 03:44:46 INFO - PROCESS | 5597 | |0;dojo.html;3221.98389008055;3238.578984731983;3249.6587858274875;3253.5933008373954;3286.2770190558067;5566.721663916803;5647.576431767624;5652.0391533439315;5665.801696940608;5675.5459563234945;5636.408177141403;5715.428457154282;5718.771249150029;5729.197912292272;5760.942390576093;4960.5039496050285;5044.798208071691;5130.076586214489;5149.098907691157;5205.9219111713355;5085.694858308495;5114;5133.1786914093755;5144.3826740791155;5155.200943853709;5056.393232812067;5087.923681144785;5095.898082038357;5103.387593488768;5125.333706618135;5135.8202462914;5164.354455693057;5164.741762911839;5175.873878011485;5202;4913.828016019422;4986.077575648516;5029.949700503022;5037.29477873098;5043.571296439807;5592.189132575794;5618.12918997552;5635.295588051494;5644.915326270109;5646.887062258773;6131.233595800525;6137.171481849982;6141.232345956755;6145.877082458326;6149.846253843645;5857.76568937242;6045.33501314855;6094.939050609526;6101.115338275969;6144.354842741537;5701.059325211335;5746.712664366765;5758.049921762904;5761.971190144022;5764.106563482667;6262.436380725757;6273.498120150377;6274.309825919146;6279.96860015697;6280.371962803683;5761.366249712528;6000.729967151488;6053.1734043243105;6083.878322433527;6088.269407671108;6129.356417576179;6133.233345831771;6136.539759518054;6141.7543298268465;6150.846228844225;6032;6099.146119543268;6102.664353460602;6119.173911521888;6139;5828;6029.487493563096;6032.336442991267;6088.299845517734;6145.877082458326;4587.61005314552;4624.237000894882;4639.559241872017;4646.070785842845;4658.091672123904;4152.501699795984;4170.874873753793;4185.874423767292;4187.937180942288;4211.894702632459;5619.381867994517;5677.716114194273;5749.051406517961;5820.854478637983;5834.008218602848;6158.076288556752;6159.630422174684;6161.4454699076405;6172.135900973867;6174.197354343906;5840.2115714378015;5853.151293062482;5863.179154918314;5872.618279811799;5879.235699359141;5076.162433198555;5188.740562971836;5224.9738751306;5228.163493841043;5232.189010703347;6248.156498872594;6313.2424109107205;6317.526185536011;6323.5889667172405;6332.39842214998;6052.546059045501;6119.449249567382;6119.694015299307;6120;6127.325994140552;6111.84720381994;6171.105189747551;6203.2245969253845;6226.40849119333;6318.210223722035;5981.252343457068;5990.7903223386975;5992.1910542078;6004.309504407049;6010.368911264254;6057.757689692539;6073.5748497604745;6086.269647642312;6086.360932102242;6087.9391206087375;5734.770609175586;5925.081612350093;5926.377730338252;5983;6000.069989151689;5915.526757859447;5941.1385349125;5946.672932988728;5968.970155149196;5969.074793407029;5853.2976042875125;5901.0558310670085;5901.262342207224;5902.6458412495385;5907.822765317047;5770.942290577208;5933.762649494144;5964.075568286922;5976.223090998143;6027.547933905063;5819.359870414166;5834;5834.38738932406;5852.3269823969085;5886.882262354815 03:44:46 INFO - PROCESS | 5597 | |1;ext.html;606.4723690389362;636.192036114135;660.4254298760079;661.190042198307;679.4666187043166;34553.48169777455;47521.28718069228;48315.51684483154;48324.51675483244;48339.51660483394;49981;50435;50525.494745052536;50532;50554.74722626382;8609.827803443928;9140.314476414262;9381.202597779196;9386.436813791195;9397.389169703947;10878.945605271963;11176.105911527058;11188.160887933396;11447.427628618578;11454.427278636076;11408.942955285213;11452.484638191301;11506.482208300604;11512.827307590393;11520.135989800756;11248.26886252391;11326.830097548544;11331.320120792696;11350.20548561601;11358.318500889973;10730.946345268301;10779.191560632984;10779.892201077968;10780.40707761073;10799.892001079967;46769.53230467686;47157;47210.76394618005;47228.05543889103;47275.29087063697;3511.6137224905497;3680.9815950920342;3818.59904710007;3899.395593682955;4096.160287141159;35821.64178358209;35837.28325433477;35876.641233587594;35929.281414371566;36197;4746.691465054761;4800;4827.806887724487;4908.410990681105;4937.382827146606;373856;374376;374423;375041;375691;52700.47299527032;52844.47155528473;53200.73399632977;53290.467095329324;53842;40287.39568906469;40959.590404096176;41232.587674123475;42779;42926.92682682895;15893;16051.117188554741;16052.438164664181;16054.75917861233;16079.196040197943;11297.830532542019;11697.649070527897;11832.585859494877;11922;11991.400429978466;21400.678989815166;21471;21486.78513214879;21529.354119376443;21660.45848853791;7467.615189013424;7531;7685.154632990367;7919.920800792033;7933.484323518953;1022.5960745505483;1092.415557676639;1141.4406940599047;1152.0347303123565;1163.2787671643466;35679.28641427209;35960.184559086774;36047.4592881107;36130.09674758152;37897.62102378941;1012.9465356029793;1103.5585765693786;1162.7965056166827;1165.8076417391035;1179.3690375648987;17149.399771008204;17923.820761792216;18113.094345282683;18172.9091354545;18206;665494;668360;669998;670333;671004;64912.350876491575;65600.34399656038;65694;65787.67106164439;65887.67056164688;1284.4926254129566;1290.7095903421655;1300.1678925487695;1340.2025794652166;1462.4150339863927;33970.8301458501;35991.64008359988;36444;36740.44889326716;36753.63246367502;55360;66035.66982165059;67460.66269668621;67636;67759.3224067753;555578;555932;556891;557054;564948;31551;31874.681253187173;31975.200619984687;33598.49602255919;49080.300583468255;53714.194287084945;54157.72921135369;54302.35108780173;55435.16847247375;56011.719941400035;45154.322685160405;45744;45795;46013.53986460093;46979.06041879075;45080;45162.548374515834;45824.78728447791;46498;47070.76464617655;45181.484917182264;45393.77303113463;46573.06853862836;46593;46945.765271173426;37369.030865702145;40257.597424025385;40940.79529602333;41193.58806411897;41347.793261033505;29744.244376517858;29906.401871962873;30373;30658.540121898637;30716 03:44:46 INFO - PROCESS | 5597 | |2;jquery.html;390.3500671382149;407.8980254936267;408.94274801527786;413.8965258685329;429.78510744627687;16521.75217371739;20100.296489622866;20133.899330503362;20148.294809681647;20361.59276814465;19305.32431364899;19794;20029.59940801187;20244.39266821994;20463.590728185427;5349.7592608332525;5881.353051164358;5914.083317085859;5940.910886336708;5979.342272350038;4361.214981303364;6451.2903580606335;6745.460363170959;6810.455163586926;6886.827829304258;5794.855128621797;6040.607360521552;6815.7955261341995;6826.726930922782;6843.692033858463;6076.848078798043;6115.327313995457;6118.449339559461;6121.173641558399;6126.632402055869;5771.942280577183;5923.318818335861;5973.671448070354;5978.641281523122;5993.340732519419;15096.622584435367;15289.388424463119;18724.719129213074;25273.62089568658;25464.61803072937;2707.093123803528;3035.5294929285997;3146.590943177372;3161.8893338733033;3164.114048066545;11148.554057837677;14725.705485890223;14776.630584235372;14997.100173989596;15891.60270993223;2046.8260197883199;2088.6553718636405;2092.7697953225133;2187.7155969723995;2219.2454565447824;79207;88020.55989720074;89603.55198224033;90398.54800726021;91704;11502.597409090642;11542.826857597143;11563.76872462546;11744.236624619543;11891.702707432383;16472;16538.917305413397;16585.17074146312;16590.917045414695;16623.418180363627;7848.254415830487;8005.319547838499;8162.83674326522;8227.218414250636;8240.917590824134;7580.317771400601;7593.126790419062;7608.277213664693;7724;7789.182135875765;4935.383077115361;5057.393112826411;5080.796768129308;5103.923441148386;5167.199084141964;1802.1367354126799;1943.2712732725229;2101.663733802615;2105.705201271823;2128.5636444528836;908.8591268353416;952.6141912525438;978.1294647763497;1020.2552136940076;1086.6468397770757;20697.551171418156;20885.582288354148;21176.258830940842;21338.679919801216;21374.78625213759;1040.105509262034;1050.2565740870118;1055.5566662001977;1077.6314081117093;1104.0339702760084;10913.39976301311;11922.761544769057;11958;12018.218815776947;12108.878911210775;418835;424980;425921;510568;564965;63063.369366306666;97340;98576.50711746396;98935;99466;1041.8280983637615;1052.6894566103017;1067.4182570499097;1078.3529882070695;1083.7019819549844;18884.15021324071;19220.903895480435;19252.133653985326;19292.32476863303;19375.903120484872;45747.77126114348;46437.303440447744;46510.30234546552;46519;47350;65582.34417655953;65866;65869.67065164643;66367.33632663805;67210.32789672236;8979.281657467256;9083.954580227057;9137.451752894847;9303.16271535538;9425;8235.423520353517;13268.071235013456;13623.114497557626;13774.311284435938;13940.860591393955;12070.879291206975;12223.69440763988;12237.265764054186;12418.37908104573;12495.687607809878;11156.55373785072;11886.108541859569;12203.877961220274;12209.267443953391;12306.138570299992;12363.330950321591;12369.403726214796;12398.5660501882;12687.11190216676;13037.217766934014;7824.92175078242;7980.640871160927;8241.351729653974;8300.792480187802;9286.907130928605;11851.52593896269;12212.08409369283;12232.694182645506;12511.249325040526;12606.684832879251 03:44:46 INFO - PROCESS | 5597 | |3;mootools.html;1456.6868123353481;1519.7492413751731;1699.796024477063;1871.6350311689225;1899.4111825334148;9353.438793672376;9994.500274986258;10093.54579043943;10110.443925584099;10119.241056920733;8593.871091933626;9305.11601397866;9523.761905952355;9554.23566114711;9866.112049915506;2560.705518865326;2594.338443696864;2600.388908606476;2601.0376160820524;2602.3364042169314;2535.936601584957;2576.935576610581;2597.766201041906;2598.5842265237566;2599.337169021897;2565.7690807827294;2569.5888657814753;2590.391258054365;2600.557905156128;2604.622329762183;2492.8628925409093;2498.71264804548;2509.77412032917;2528.3173543143434;2542.1102614085107;2493.6508888755407;2503.311589312935;2512.5351809915223;2522.1928982725535;2524.6465494830736;10036.548355324028;10105;10123.1901447884;10123.443210623413;10130.037646423578;5871.911921321183;6029.0353543433275;6030.396960303979;6036.3360030396625;6046.879062418727;3677.2461645362637;3730.1607138393915;3849.2878817418587;3860.826262818152;3877.9806100969313;1241.3979220078254;1274.7769140400485;1288.8195652608642;1289.374653293152;1316.4141956829214;13819.963502737235;13900.93049534756;13906.93046534771;13936.790948135786;13949.16305021702;5018.146915024455;5202.4277329493725;5296.364436267596;5306.09796334624;5370.3287089113865;11545.134114941413;11559.76880462386;11583.710407239718;11584.594539191088;11593.82609260845;5930.229070220844;5939.4951429129005;5940.34656187819;5946.732397042144;5948.643081415129;4757.548032936866;4802.73584952831;5321.707306098125;5412.56699464042;5440.863978400572;4397.956020439755;4430.800613972379;4493.573110554492;4519.7740112994215;4536.637069034469;1402.102654301248;1404.445244128584;1417.2134465371817;1424.302091974925;1433.7419264532275;986.7039888033561;1022.4274406332478;1077.5528155815248;1079.3308148947704;1093.092733031582;6475.805725828232;6524.836879078061;6791;6832.624205668736;6961.852928504545;494.5894907226993;500.13726322094095;504.53330669130906;506.7896822818489;516.8423630792569;4585.564371384713;4647.581717645428;4653.418322709661;4762.14281429346;4786.305985632064;12098.274103553815;12394.380280986095;12527.624171274876;12535;12579.119461637598;11338.376389298503;11427.714307142389;11445.370504622158;11713.414329283502;12438;8629.223369896614;8857.981332146757;8886.066962968924;9034.141756532987;9044.231240344645;6569.178852643419;6777.152855893013;6780.99019729495;6832.760853370109;6863.142107236596;10619.1504679627;10717.785644287227;10885.782284354427;11254.38100904458;11476.139289553485;10252.64115755945;10608.575656973635;11175.385353805294;11336.829947550958;11736.647900562997;9053.864192036994;9210.907890921006;9330.253579713471;9397.624095036123;9588.76028099303;9300.348975571917;9769.706908792748;9834.655787047419;9846.556904939438;9853.06395892389;8376.078631350425;8425.70510032146;8502.74491765248;8556.74329770108;8609.526476043879;7977.5612341321785;8062.717804876801;8325.791855203668;8350.665973360998;8516.531590762568;7896.644650990834;7901.17037711032;7937.484063535851;7965.004374453193;7972.681092756457;5649.802256920988;5710.086386178192;5762.971185144215;5796.59423840344;5849.912251316318;5786.103154011135;5820.796272130455;5842.6561890764515;5877.382874798084;5899.351071382059 03:44:46 INFO - PROCESS | 5597 | |4;prototype.html;356.8091071276867;363.2408266722549;364.82488405565334;364.86499995001856;369.2872755581726;5606.719664016803;5610.579206559509;5617.354004289513;5618.2415373924505;5636.436356364362;5381.354237491507;5479.917801232975;5549.112142057262;5584.581156413265;5590.496855283024;4351.5648435156545;4360.171567402183;4396.406485124515;4403.823847046115;4408.294672852344;4336.3278691802825;4344.435223420951;4374.4313239278845;4380.8685739427865;4390.5609439056;4336.761478118702;4348.826046958118;4363.54182810805;4368.759718215497;4377.409049778287;4299.161663475624;4327.242732521796;4352.7823608819435;4354.1277250782;4356.150550642626;3897.162110146304;3912.902177445558;3914.706397020235;3916.4516967624554;3937.3503371943593;5370.355557333145;5421;5464.890702185974;5472.644278121909;5473.8357849264585;4863.513648635108;4999.075355987934;5037.269595908573;5039.672421292605;5070.771815268322;5781.913271300934;5831.504322132624;5832.1834943108;5837.445442682938;5855.092460668603;4351.608355248011;4357.891052723676;4371.300591905312;4372.125574885035;4376.452943382077;5723.627964182315;5731.770729170828;5932.288125424933;6037.939620603782;6061.575689701723;4392.165488557164;4393.560643935581;4394.12117576486;4396.868093957187;4403.3174857897075;4459.0858873930765;4466.3747075409465;4467.195904737114;4476.306172543196;4477.462704475483;4615.530691080256;4839.733814640158;4996.325496057985;5020.497950205024;5033.84898453047;5674;5675.290588676416;5689.34572524165;5714.685692286964;5716.914246286309;1487.293535570617;1514.5611668914355;1514.7500662390803;1523.0785374848058;1525.2983627531237;948.9620415183454;1003.8644782954353;1007.1539906478594;1011.5245834457793;1025.963776585654;4995.575376093073;5002.399712034507;5009.849704508871;5040.571551418097;5058.418281897629;5226.529612334908;5244.5279924806955;5247.370315562157;5249.370075590953;5264.763085661154;4790.682562295397;4796.4891059316205;4947.356843610308;4968.527989840959;4984.850454486371;2806.733360330765;2810.170999555136;2813.043565187846;2816.605675205473;2819.2951762059483;5412.188171774186;5420.6691122817565;5426.294581704353;5427.83716488506;5472.808451704172;5000.87480316933;5240.580753539708;5255.39562950258;5269.657472264291;5334.998127107005;5357.732113394315;5362.195670649434;5386.973065134649;5398.055340315507;5403.081476149064;5246.842594722164;5255.421903590678;5275.762590683506;5302.787888484418;5312.654677445954;5501.367342755474;5907.763689452374;5929.6145750526075;5935.77408471823;5937.168796368598;5895.882082358243;5974.671393073423;6126.509879209563;6166.81499555014;6195.690215489118;4252.234597772431;4301.07526881722;4308.676849236257;4326.545712700247;4328.112736888867;6043.184170137151;6047.213862197887;6047.518358002318;6051.33435322123;6056.939430605814;5676.290463692038;5694.772209111589;5739.053056245672;5762.337331206965;5770.336411312753;5749.770009199585;5779.335376431764;5784.942150578609;5795.188673585617;5888.499477544286;5477.835664930059;5725.914111288251;5725.942740572541;5747.19539264495;5811.331696854916;5818.458108601139;5834.912476312944;5874.735636896265;5881.735321910439;5885.675722962299;5733.827985160452;5750.706091129462;5776.277965254343;5789.1605717171615;5790.623609465371 03:44:46 INFO - PROCESS | 5597 | |5;yui.html;784.7488803582855;793.8015496125969;861.014138811061;876.9429987050842;902.3593248793359;16296;17624.82375176248;17651.382201622913;17660.911695441522;17664.29342826287;15985.120818354986;16268.593285167877;16283.430079947204;16283.918580407111;16292.674146517062;726.2664708644281;753.5102183580661;755.2145768400857;756.8334966307431;853.4068822168596;803.7146812881431;877.5261358866204;884.2969838978028;884.7080463447079;885.402353411448;937.5921474158729;939.2579861909118;939.6570251858057;940.4780346907463;940.5156344482597;773.2036002916948;937.3485427627801;937.8358787212211;938.0431959401373;938.6057855700589;671.788386658206;686.36168363422;686.7321744519642;686.9003994420831;688.2876223109108;15136;15153.393864245418;15179.772303415457;15199.544013679608;15209.923950380287;13496.932515337361;14509;15212.239388030554;15295.235238238043;15471.381144754196;14337.71324573513;14346.497872574515;14434.278286085653;14759.704805903822;14864.479743208936;4700.694454860423;4811.59101476375;4911.729854857981;4930.827421040282;4931.90136197274;88864;88879;88884.55557722235;88989;89054.55472722594;11322.94338528302;11650.126240531825;11650.53397864093;11921.46353414081;12441.377931103409;21977.23079692203;22022.449438763837;22025.118995240333;22066.558668826532;22068.448288792588;9135.223506002065;9239.953800230956;9298.256139508823;9519.23846092311;9546.92637118305;5183.792648294101;5324.094903866352;5364.329458817648;5425.213344065089;5511.614187006871;3777.792221427848;3840.1935593525113;3882.5729169791302;3889.5527014393706;3893.7858417787293;654.8231977366084;664.1697877652933;667.6995352091578;668.6556423441931;695.5687473766196;687.6802286936498;703.3353480960545;730.5799165479871;753.1338960195818;781.2109769133216;9711.223102151953;10098.192144628412;10274.794504109876;10367.349794362091;10653.733656658645;643.2955913274957;644.9000404937242;645.3481983197005;648.6293489903605;694.4964900447236;5145.15105007677;5216.9739151304;5217.739113044333;5608.747606357723;5657.010023245915;119269;119891.40054299847;120684;122891;124138;105356;107522.46238768913;108527;108682.45658771656;111005;97305;97392;97432;97778;100040.49979750055;9649.710508684751;9904.603815847573;10164;10197.9490102549;10264.281500294908;54344;55064.72467637636;55408;56649.150262746916;58155;47678.52321476741;59157.70421148039;79389.60305198438;81261.59369203117;82171.58914205391;11764.117691173025;12090.939545302217;12092.153549251469;12160.45277962476;12324.568640097554;14153.787693184817;15244.77132842986;15540;15740.370385184464;15799.763003554726;15421.383144674088;15464.14947177871;16520.173991300147;16627.3349066036;16662.50012499627;15359.769603456181;15500;15571.688566228839;16694.833051669328;16843.747343789608;15282.388704452142;15309.23453827282;16206.270717817963;16510.66978660396;16513.587160320607;11998.940005299917;12159.148859580098;12185.69535761613;12726.618201453972;12832.358382080673;12913.806292905427;13041.282729449591;13132.34338283063;13157.53948611794;13389.732205355644 03:44:46 INFO - PROCESS | 5597 | __end_tp_report 03:44:46 INFO - PROCESS | 5597 | __start_cc_report 03:44:46 INFO - PROCESS | 5597 | _x_x_mozilla_cycle_collect,1159 03:44:46 INFO - PROCESS | 5597 | __end_cc_report 03:44:46 INFO - PROCESS | 5597 | __startTimestamp1472899486777__endTimestamp 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | ------- Summary: start ------- 03:44:46 INFO - PROCESS | 5597 | Number of tests: 6 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#0] dojo.html Cycles:165 Average:5642.12 Median:5863.18 stddev:658.09 (11.2%) stddev-sans-first:632.13 03:44:46 INFO - PROCESS | 5597 | Values: 3222.0 3238.6 3249.7 3253.6 3286.3 5566.7 5647.6 5652.0 5665.8 5675.5 5636.4 5715.4 5718.8 5729.2 5760.9 4960.5 5044.8 5130.1 5149.1 5205.9 5085.7 5114.0 5133.2 5144.4 5155.2 5056.4 5087.9 5095.9 5103.4 5125.3 5135.8 5164.4 5164.7 5175.9 5202.0 4913.8 4986.1 5029.9 5037.3 5043.6 5592.2 5618.1 5635.3 5644.9 5646.9 6131.2 6137.2 6141.2 6145.9 6149.8 5857.8 6045.3 6094.9 6101.1 6144.4 5701.1 5746.7 5758.0 5762.0 5764.1 6262.4 6273.5 6274.3 6280.0 6280.4 5761.4 6000.7 6053.2 6083.9 6088.3 6129.4 6133.2 6136.5 6141.8 6150.8 6032.0 6099.1 6102.7 6119.2 6139.0 5828.0 6029.5 6032.3 6088.3 6145.9 4587.6 4624.2 4639.6 4646.1 4658.1 4152.5 4170.9 4185.9 4187.9 4211.9 5619.4 5677.7 5749.1 5820.9 5834.0 6158.1 6159.6 6161.4 6172.1 6174.2 5840.2 5853.2 5863.2 5872.6 5879.2 5076.2 5188.7 5225.0 5228.2 5232.2 6248.2 6313.2 6317.5 6323.6 6332.4 6052.5 6119.4 6119.7 6120.0 6127.3 6111.8 6171.1 6203.2 6226.4 6318.2 5981.3 5990.8 5992.2 6004.3 6010.4 6057.8 6073.6 6086.3 6086.4 6087.9 5734.8 5925.1 5926.4 5983.0 6000.1 5915.5 5941.1 5946.7 5969.0 5969.1 5853.3 5901.1 5901.3 5902.6 5907.8 5770.9 5933.8 5964.1 5976.2 6027.5 5819.4 5834.0 5834.4 5852.3 5886.9 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#1] ext.html Cycles:180 Average:69967.82 Median:40272.50 stddev:145907.32 (362.3%) stddev-sans-first:146223.69 03:44:46 INFO - PROCESS | 5597 | Values: 606.5 636.2 660.4 661.2 679.5 34553.5 47521.3 48315.5 48324.5 48339.5 49981.0 50435.0 50525.5 50532.0 50554.7 8609.8 9140.3 9381.2 9386.4 9397.4 10878.9 11176.1 11188.2 11447.4 11454.4 11408.9 11452.5 11506.5 11512.8 11520.1 11248.3 11326.8 11331.3 11350.2 11358.3 10730.9 10779.2 10779.9 10780.4 10799.9 46769.5 47157.0 47210.8 47228.1 47275.3 3511.6 3681.0 3818.6 3899.4 4096.2 35821.6 35837.3 35876.6 35929.3 36197.0 4746.7 4800.0 4827.8 4908.4 4937.4 373856.0 374376.0 374423.0 375041.0 375691.0 52700.5 52844.5 53200.7 53290.5 53842.0 40287.4 40959.6 41232.6 42779.0 42926.9 15893.0 16051.1 16052.4 16054.8 16079.2 11297.8 11697.6 11832.6 11922.0 11991.4 21400.7 21471.0 21486.8 21529.4 21660.5 7467.6 7531.0 7685.2 7919.9 7933.5 1022.6 1092.4 1141.4 1152.0 1163.3 35679.3 35960.2 36047.5 36130.1 37897.6 1012.9 1103.6 1162.8 1165.8 1179.4 17149.4 17923.8 18113.1 18172.9 18206.0 665494.0 668360.0 669998.0 670333.0 671004.0 64912.4 65600.3 65694.0 65787.7 65887.7 1284.5 1290.7 1300.2 1340.2 1462.4 33970.8 35991.6 36444.0 36740.4 36753.6 55360.0 66035.7 67460.7 67636.0 67759.3 555578.0 555932.0 556891.0 557054.0 564948.0 31551.0 31874.7 31975.2 33598.5 49080.3 53714.2 54157.7 54302.4 55435.2 56011.7 45154.3 45744.0 45795.0 46013.5 46979.1 45080.0 45162.5 45824.8 46498.0 47070.8 45181.5 45393.8 46573.1 46593.0 46945.8 37369.0 40257.6 40940.8 41193.6 41347.8 29744.2 29906.4 30373.0 30658.5 30716.0 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#2] jquery.html Cycles:180 Average:29341.33 Median:14085.86 stddev:78277.09 (555.7%) stddev-sans-first:78466.49 03:44:46 INFO - PROCESS | 5597 | Values: 390.4 407.9 408.9 413.9 429.8 16521.8 20100.3 20133.9 20148.3 20361.6 19305.3 19794.0 20029.6 20244.4 20463.6 5349.8 5881.4 5914.1 5940.9 5979.3 4361.2 6451.3 6745.5 6810.5 6886.8 5794.9 6040.6 6815.8 6826.7 6843.7 6076.8 6115.3 6118.4 6121.2 6126.6 5771.9 5923.3 5973.7 5978.6 5993.3 15096.6 15289.4 18724.7 25273.6 25464.6 2707.1 3035.5 3146.6 3161.9 3164.1 11148.6 14725.7 14776.6 14997.1 15891.6 2046.8 2088.7 2092.8 2187.7 2219.2 79207.0 88020.6 89603.6 90398.5 91704.0 11502.6 11542.8 11563.8 11744.2 11891.7 16472.0 16538.9 16585.2 16590.9 16623.4 7848.3 8005.3 8162.8 8227.2 8240.9 7580.3 7593.1 7608.3 7724.0 7789.2 4935.4 5057.4 5080.8 5103.9 5167.2 1802.1 1943.3 2101.7 2105.7 2128.6 908.9 952.6 978.1 1020.3 1086.6 20697.6 20885.6 21176.3 21338.7 21374.8 1040.1 1050.3 1055.6 1077.6 1104.0 10913.4 11922.8 11958.0 12018.2 12108.9 418835.0 424980.0 425921.0 510568.0 564965.0 63063.4 97340.0 98576.5 98935.0 99466.0 1041.8 1052.7 1067.4 1078.4 1083.7 18884.2 19220.9 19252.1 19292.3 19375.9 45747.8 46437.3 46510.3 46519.0 47350.0 65582.3 65866.0 65869.7 66367.3 67210.3 8979.3 9084.0 9137.5 9303.2 9425.0 8235.4 13268.1 13623.1 13774.3 13940.9 12070.9 12223.7 12237.3 12418.4 12495.7 11156.6 11886.1 12203.9 12209.3 12306.1 12363.3 12369.4 12398.6 12687.1 13037.2 7824.9 7980.6 8241.4 8300.8 9286.9 11851.5 12212.1 12232.7 12511.2 12606.7 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#3] mootools.html Cycles:180 Average:6463.89 Median:4528.21 stddev:3698.32 (81.7%) stddev-sans-first:3689.55 03:44:46 INFO - PROCESS | 5597 | Values: 1456.7 1519.7 1699.8 1871.6 1899.4 9353.4 9994.5 10093.5 10110.4 10119.2 8593.9 9305.1 9523.8 9554.2 9866.1 2560.7 2594.3 2600.4 2601.0 2602.3 2535.9 2576.9 2597.8 2598.6 2599.3 2565.8 2569.6 2590.4 2600.6 2604.6 2492.9 2498.7 2509.8 2528.3 2542.1 2493.7 2503.3 2512.5 2522.2 2524.6 10036.5 10105.0 10123.2 10123.4 10130.0 5871.9 6029.0 6030.4 6036.3 6046.9 3677.2 3730.2 3849.3 3860.8 3878.0 1241.4 1274.8 1288.8 1289.4 1316.4 13820.0 13900.9 13906.9 13936.8 13949.2 5018.1 5202.4 5296.4 5306.1 5370.3 11545.1 11559.8 11583.7 11584.6 11593.8 5930.2 5939.5 5940.3 5946.7 5948.6 4757.5 4802.7 5321.7 5412.6 5440.9 4398.0 4430.8 4493.6 4519.8 4536.6 1402.1 1404.4 1417.2 1424.3 1433.7 986.7 1022.4 1077.6 1079.3 1093.1 6475.8 6524.8 6791.0 6832.6 6961.9 494.6 500.1 504.5 506.8 516.8 4585.6 4647.6 4653.4 4762.1 4786.3 12098.3 12394.4 12527.6 12535.0 12579.1 11338.4 11427.7 11445.4 11713.4 12438.0 8629.2 8858.0 8886.1 9034.1 9044.2 6569.2 6777.2 6781.0 6832.8 6863.1 10619.2 10717.8 10885.8 11254.4 11476.1 10252.6 10608.6 11175.4 11336.8 11736.6 9053.9 9210.9 9330.3 9397.6 9588.8 9300.3 9769.7 9834.7 9846.6 9853.1 8376.1 8425.7 8502.7 8556.7 8609.5 7977.6 8062.7 8325.8 8350.7 8516.5 7896.6 7901.2 7937.5 7965.0 7972.7 5649.8 5710.1 5763.0 5796.6 5849.9 5786.1 5820.8 5842.7 5877.4 5899.4 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#4] prototype.html Cycles:180 Average:4771.62 Median:5252.38 stddev:1361.38 (25.9%) stddev-sans-first:1324.26 03:44:46 INFO - PROCESS | 5597 | Values: 356.8 363.2 364.8 364.9 369.3 5606.7 5610.6 5617.4 5618.2 5636.4 5381.4 5479.9 5549.1 5584.6 5590.5 4351.6 4360.2 4396.4 4403.8 4408.3 4336.3 4344.4 4374.4 4380.9 4390.6 4336.8 4348.8 4363.5 4368.8 4377.4 4299.2 4327.2 4352.8 4354.1 4356.2 3897.2 3912.9 3914.7 3916.5 3937.4 5370.4 5421.0 5464.9 5472.6 5473.8 4863.5 4999.1 5037.3 5039.7 5070.8 5781.9 5831.5 5832.2 5837.4 5855.1 4351.6 4357.9 4371.3 4372.1 4376.5 5723.6 5731.8 5932.3 6037.9 6061.6 4392.2 4393.6 4394.1 4396.9 4403.3 4459.1 4466.4 4467.2 4476.3 4477.5 4615.5 4839.7 4996.3 5020.5 5033.8 5674.0 5675.3 5689.3 5714.7 5716.9 1487.3 1514.6 1514.8 1523.1 1525.3 949.0 1003.9 1007.2 1011.5 1026.0 4995.6 5002.4 5009.8 5040.6 5058.4 5226.5 5244.5 5247.4 5249.4 5264.8 4790.7 4796.5 4947.4 4968.5 4984.9 2806.7 2810.2 2813.0 2816.6 2819.3 5412.2 5420.7 5426.3 5427.8 5472.8 5000.9 5240.6 5255.4 5269.7 5335.0 5357.7 5362.2 5387.0 5398.1 5403.1 5246.8 5255.4 5275.8 5302.8 5312.7 5501.4 5907.8 5929.6 5935.8 5937.2 5895.9 5974.7 6126.5 6166.8 6195.7 4252.2 4301.1 4308.7 4326.5 4328.1 6043.2 6047.2 6047.5 6051.3 6056.9 5676.3 5694.8 5739.1 5762.3 5770.3 5749.8 5779.3 5784.9 5795.2 5888.5 5477.8 5725.9 5725.9 5747.2 5811.3 5818.5 5834.9 5874.7 5881.7 5885.7 5733.8 5750.7 5776.3 5789.2 5790.6 03:44:46 INFO - PROCESS | 5597 | 03:44:46 INFO - PROCESS | 5597 | [#5] yui.html Cycles:180 Average:22539.67 Median:3886.06 stddev:32413.62 (834.1%) stddev-sans-first:32463.38 03:44:46 INFO - PROCESS | 5597 | Values: 784.7 793.8 861.0 876.9 902.4 16296.0 17624.8 17651.4 17660.9 17664.3 15985.1 16268.6 16283.4 16283.9 16292.7 726.3 753.5 755.2 756.8 853.4 803.7 877.5 884.3 884.7 885.4 937.6 939.3 939.7 940.5 940.5 773.2 937.3 937.8 938.0 938.6 671.8 686.4 686.7 686.9 688.3 15136.0 15153.4 15179.8 15199.5 15209.9 13496.9 14509.0 15212.2 15295.2 15471.4 14337.7 14346.5 14434.3 14759.7 14864.5 4700.7 4811.6 4911.7 4930.8 4931.9 88864.0 88879.0 88884.6 88989.0 89054.6 11322.9 11650.1 11650.5 11921.5 12441.4 21977.2 22022.4 22025.1 22066.6 22068.4 9135.2 9240.0 9298.3 9519.2 9546.9 5183.8 5324.1 5364.3 5425.2 5511.6 3777.8 3840.2 3882.6 3889.6 3893.8 654.8 664.2 667.7 668.7 695.6 687.7 703.3 730.6 753.1 781.2 9711.2 10098.2 10274.8 10367.3 10653.7 643.3 644.9 645.3 648.6 694.5 5145.2 5217.0 5217.7 5608.7 5657.0 119269.0 119891.4 120684.0 122891.0 124138.0 105356.0 107522.5 108527.0 108682.5 111005.0 97305.0 97392.0 97432.0 97778.0 100040.5 9649.7 9904.6 10164.0 10197.9 10264.3 54344.0 55064.7 55408.0 56649.2 58155.0 47678.5 59157.7 79389.6 81261.6 82171.6 11764.1 12090.9 12092.2 12160.5 12324.6 14153.8 15244.8 15540.0 15740.4 15799.8 15421.4 15464.1 16520.2 16627.3 16662.5 15359.8 15500.0 15571.7 16694.8 16843.7 15282.4 15309.2 16206.3 16510.7 16513.6 11998.9 12159.1 12185.7 12726.6 12832.4 12913.8 13041.3 13132.3 13157.5 13389.7 03:44:46 INFO - PROCESS | 5597 | -------- Summary: end -------- 03:44:46 INFO - PROCESS | 5597 | 03:44:47 INFO - TEST-INFO | 5597: exit 0 03:44:47 INFO - TEST-OK | dromaeo_css | took 1115153ms 03:44:47 INFO - TEST-START | kraken 03:44:47 INFO - operating with platform_type : linux_ 03:44:47 INFO - Initialising browser for kraken test... 03:44:47 INFO - TEST-INFO | started process 5801 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi3b_vb/profile http://localhost:41386/getInfo.html) 03:44:51 INFO - PROCESS | 5801 | __metrics Screen width/height:1600/1200 03:44:51 INFO - PROCESS | 5801 | colorDepth:24 03:44:51 INFO - PROCESS | 5801 | Browser inner width/height: 1024/697 03:44:51 INFO - PROCESS | 5801 | __metrics 03:44:53 INFO - PROCESS | 5801 | 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" 03:44:53 INFO - PROCESS | 5801 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:54 INFO - TEST-INFO | 5801: exit 0 03:44:54 INFO - Browser initialized. 03:44:54 INFO - Running cycle 1/1 for kraken test... 03:44:54 INFO - TEST-INFO | started process 5888 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi3b_vb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:05 INFO - PROCESS | 5888 | RSS: Main: 145121280 03:45:05 INFO - PROCESS | 5888 | 03:45:08 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41386/tests/kraken/driver.html?testName=audio-beat-detection) 03:45:09 INFO - PROCESS | 5888 | RSS: Main: 190816256 03:45:09 INFO - PROCESS | 5888 | 03:45:11 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41386/tests/kraken/driver.html?testName=audio-dft) 03:45:12 INFO - PROCESS | 5888 | RSS: Main: 181710848 03:45:12 INFO - PROCESS | 5888 | 03:45:15 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41386/tests/kraken/driver.html?testName=audio-fft) 03:45:15 INFO - PROCESS | 5888 | RSS: Main: 271118336 03:45:15 INFO - PROCESS | 5888 | 03:45:17 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41386/tests/kraken/driver.html?testName=audio-oscillator) 03:45:18 INFO - PROCESS | 5888 | RSS: Main: 175767552 03:45:18 INFO - PROCESS | 5888 | 03:45:20 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41386/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:45:21 INFO - PROCESS | 5888 | RSS: Main: 177160192 03:45:21 INFO - PROCESS | 5888 | 03:45:24 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41386/tests/kraken/driver.html?testName=imaging-darkroom) 03:45:24 INFO - PROCESS | 5888 | RSS: Main: 212918272 03:45:24 INFO - PROCESS | 5888 | 03:45:27 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41386/tests/kraken/driver.html?testName=imaging-desaturate) 03:45:28 INFO - PROCESS | 5888 | RSS: Main: 212004864 03:45:28 INFO - PROCESS | 5888 | 03:45:31 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41386/tests/kraken/driver.html?testName=json-parse-financial) 03:45:31 INFO - PROCESS | 5888 | RSS: Main: 211791872 03:45:31 INFO - PROCESS | 5888 | 03:45:33 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41386/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:45:34 INFO - PROCESS | 5888 | RSS: Main: 175665152 03:45:34 INFO - PROCESS | 5888 | 03:45:35 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:45:36 INFO - PROCESS | 5888 | RSS: Main: 181932032 03:45:36 INFO - PROCESS | 5888 | 03:45:38 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:45:38 INFO - PROCESS | 5888 | RSS: Main: 186261504 03:45:38 INFO - PROCESS | 5888 | 03:45:41 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:45:42 INFO - PROCESS | 5888 | RSS: Main: 180908032 03:45:42 INFO - PROCESS | 5888 | 03:45:44 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:45:45 INFO - PROCESS | 5888 | RSS: Main: 184467456 03:45:45 INFO - PROCESS | 5888 | 03:45:46 INFO - PROCESS | 5888 | Cycle 1(1): loaded http://localhost:41386/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41386/tests/kraken/driver.html?testName=ai-astar) 03:45:47 INFO - PROCESS | 5888 | RSS: Main: 235700224 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | __start_tp_report 03:45:47 INFO - PROCESS | 5888 | _x_x_mozilla_page_load 03:45:47 INFO - PROCESS | 5888 | _x_x_mozilla_page_load_details 03:45:47 INFO - PROCESS | 5888 | |i|pagename|runs| 03:45:47 INFO - PROCESS | 5888 | |0;ai-astar;99;112;110;109;111;111;110;111;110;110 03:45:47 INFO - PROCESS | 5888 | |1;audio-beat-detection;144;118;118;178;115;123;153;116;143;120 03:45:47 INFO - PROCESS | 5888 | |2;audio-dft;160;160;160;159;160;163;162;159;158;159 03:45:47 INFO - PROCESS | 5888 | |3;audio-fft;74;75;74;110;76;77;75;75;108;75 03:45:47 INFO - PROCESS | 5888 | |4;audio-oscillator;84;82;132;83;83;84;119;82;83;137 03:45:47 INFO - PROCESS | 5888 | |5;imaging-gaussian-blur;117;118;117;118;117;117;117;117;117;117 03:45:47 INFO - PROCESS | 5888 | |6;imaging-darkroom;145;146;145;146;145;146;146;146;146;145 03:45:47 INFO - PROCESS | 5888 | |7;imaging-desaturate;95;95;96;96;96;95;95;94;95;95 03:45:47 INFO - PROCESS | 5888 | |8;json-parse-financial;50;49;51;49;49;51;51;50;50;51 03:45:47 INFO - PROCESS | 5888 | |9;json-stringify-tinderbox;58;70;58;59;59;60;58;58;59;59 03:45:47 INFO - PROCESS | 5888 | |10;stanford-crypto-aes;73;72;72;80;72;72;73;71;80;72 03:45:47 INFO - PROCESS | 5888 | |11;stanford-crypto-ccm;118;116;124;120;116;115;116;125;152;120 03:45:47 INFO - PROCESS | 5888 | |12;stanford-crypto-pbkdf2;150;150;151;158;157;149;151;150;149;157 03:45:47 INFO - PROCESS | 5888 | |13;stanford-crypto-sha256-iterative;62;61;61;61;61;63;60;62;61;62 03:45:47 INFO - PROCESS | 5888 | __end_tp_report 03:45:47 INFO - PROCESS | 5888 | __start_cc_report 03:45:47 INFO - PROCESS | 5888 | _x_x_mozilla_cycle_collect,1624 03:45:47 INFO - PROCESS | 5888 | __end_cc_report 03:45:47 INFO - PROCESS | 5888 | __startTimestamp1472899547101__endTimestamp 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | ------- Summary: start ------- 03:45:47 INFO - PROCESS | 5888 | Number of tests: 14 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:3.71 (3.3%) stddev-sans-first:0.88 03:45:47 INFO - PROCESS | 5888 | Values: 99.0 112.0 110.0 109.0 111.0 111.0 110.0 111.0 110.0 110.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:133.00 stddev:21.02 (15.8%) stddev-sans-first:21.90 03:45:47 INFO - PROCESS | 5888 | Values: 144.0 118.0 118.0 178.0 115.0 123.0 153.0 116.0 143.0 120.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 03:45:47 INFO - PROCESS | 5888 | Values: 160.0 160.0 160.0 159.0 160.0 163.0 162.0 159.0 158.0 159.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:14.32 (19.1%) stddev-sans-first:14.90 03:45:47 INFO - PROCESS | 5888 | Values: 74.0 75.0 74.0 110.0 76.0 77.0 75.0 75.0 108.0 75.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:83.00 stddev:22.82 (27.5%) stddev-sans-first:23.72 03:45:47 INFO - PROCESS | 5888 | Values: 84.0 82.0 132.0 83.0 83.0 84.0 119.0 82.0 83.0 137.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:45:47 INFO - PROCESS | 5888 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:45:47 INFO - PROCESS | 5888 | Values: 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 03:45:47 INFO - PROCESS | 5888 | Values: 95.0 95.0 96.0 96.0 96.0 95.0 95.0 94.0 95.0 95.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.50 stddev:0.88 (1.7%) stddev-sans-first:0.93 03:45:47 INFO - PROCESS | 5888 | Values: 50.0 49.0 51.0 49.0 49.0 51.0 51.0 50.0 50.0 51.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:59.00 stddev:3.65 (6.2%) stddev-sans-first:3.81 03:45:47 INFO - PROCESS | 5888 | Values: 58.0 70.0 58.0 59.0 59.0 60.0 58.0 58.0 59.0 59.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:72.50 stddev:3.37 (4.6%) stddev-sans-first:3.56 03:45:47 INFO - PROCESS | 5888 | Values: 73.0 72.0 72.0 80.0 72.0 72.0 73.0 71.0 80.0 72.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:120.00 stddev:11.02 (9.2%) stddev-sans-first:11.59 03:45:47 INFO - PROCESS | 5888 | Values: 118.0 116.0 124.0 120.0 116.0 115.0 116.0 125.0 152.0 120.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:151.00 stddev:3.61 (2.4%) stddev-sans-first:3.75 03:45:47 INFO - PROCESS | 5888 | Values: 150.0 150.0 151.0 158.0 157.0 149.0 151.0 150.0 149.0 157.0 03:45:47 INFO - PROCESS | 5888 | 03:45:47 INFO - PROCESS | 5888 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.50 stddev:0.84 (1.4%) stddev-sans-first:0.87 03:45:47 INFO - PROCESS | 5888 | Values: 62.0 61.0 61.0 61.0 61.0 63.0 60.0 62.0 61.0 62.0 03:45:47 INFO - PROCESS | 5888 | -------- Summary: end -------- 03:45:47 INFO - PROCESS | 5888 | 03:45:48 INFO - TEST-INFO | 5888: exit 0 03:45:48 INFO - TEST-OK | kraken | took 60646ms 03:45:48 INFO - SUITE-END | took 1175s 03:45:48 INFO - Completed test suite (00:19:35) 03:45:48 INFO - javascript benchmark 03:45:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3221.98389008055, 3238.578984731983, 3249.6587858274875, 3253.5933008373954, 3286.2770190558067, 5566.721663916803, 5647.576431767624, 5652.0391533439315, 5665.801696940608, 5675.5459563234945, 5636.408177141403, 5715.428457154282, 5718.771249150029, 5729.197912292272, 5760.942390576093, 4960.5039496050285, 5044.798208071691, 5130.076586214489, 5149.098907691157, 5205.9219111713355, 5085.694858308495, 5114.0, 5133.1786914093755, 5144.3826740791155, 5155.200943853709, 5056.393232812067, 5087.923681144785, 5095.898082038357, 5103.387593488768, 5125.333706618135, 5135.8202462914, 5164.354455693057, 5164.741762911839, 5175.873878011485, 5202.0, 4913.828016019422, 4986.077575648516, 5029.949700503022, 5037.29477873098, 5043.571296439807, 5592.189132575794, 5618.12918997552, 5635.295588051494, 5644.915326270109, 5646.887062258773, 6131.233595800525, 6137.171481849982, 6141.232345956755, 6145.877082458326, 6149.846253843645, 5857.76568937242, 6045.33501314855, 6094.939050609526, 6101.115338275969, 6144.354842741537, 5701.059325211335, 5746.712664366765, 5758.049921762904, 5761.971190144022, 5764.106563482667, 6262.436380725757, 6273.498120150377, 6274.309825919146, 6279.96860015697, 6280.371962803683, 5761.366249712528, 6000.729967151488, 6053.1734043243105, 6083.878322433527, 6088.269407671108, 6129.356417576179, 6133.233345831771, 6136.539759518054, 6141.7543298268465, 6150.846228844225, 6032.0, 6099.146119543268, 6102.664353460602, 6119.173911521888, 6139.0, 5828.0, 6029.487493563096, 6032.336442991267, 6088.299845517734, 6145.877082458326, 4587.61005314552, 4624.237000894882, 4639.559241872017, 4646.070785842845, 4658.091672123904, 4152.501699795984, 4170.874873753793, 4185.874423767292, 4187.937180942288, 4211.894702632459, 5619.381867994517, 5677.716114194273, 5749.051406517961, 5820.854478637983, 5834.008218602848, 6158.076288556752, 6159.630422174684, 6161.4454699076405, 6172.135900973867, 6174.197354343906, 5840.2115714378015, 5853.151293062482, 5863.179154918314, 5872.618279811799, 5879.235699359141, 5076.162433198555, 5188.740562971836, 5224.9738751306, 5228.163493841043, 5232.189010703347, 6248.156498872594, 6313.2424109107205, 6317.526185536011, 6323.5889667172405, 6332.39842214998, 6052.546059045501, 6119.449249567382, 6119.694015299307, 6120.0, 6127.325994140552, 6111.84720381994, 6171.105189747551, 6203.2245969253845, 6226.40849119333, 6318.210223722035, 5981.252343457068, 5990.7903223386975, 5992.1910542078, 6004.309504407049, 6010.368911264254, 6057.757689692539, 6073.5748497604745, 6086.269647642312, 6086.360932102242, 6087.9391206087375, 5734.770609175586, 5925.081612350093, 5926.377730338252, 5983.0, 6000.069989151689, 5915.526757859447, 5941.1385349125, 5946.672932988728, 5968.970155149196, 5969.074793407029, 5853.2976042875125, 5901.0558310670085, 5901.262342207224, 5902.6458412495385, 5907.822765317047, 5770.942290577208, 5933.762649494144, 5964.075568286922, 5976.223090998143, 6027.547933905063, 5819.359870414166, 5834.0, 5834.38738932406, 5852.3269823969085, 5886.882262354815], "value": 5596.535372709567, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.4723690389362, 636.192036114135, 660.4254298760079, 661.190042198307, 679.4666187043166, 34553.48169777455, 47521.28718069228, 48315.51684483154, 48324.51675483244, 48339.51660483394, 49981.0, 50435.0, 50525.494745052536, 50532.0, 50554.74722626382, 8609.827803443928, 9140.314476414262, 9381.202597779196, 9386.436813791195, 9397.389169703947, 10878.945605271963, 11176.105911527058, 11188.160887933396, 11447.427628618578, 11454.427278636076, 11408.942955285213, 11452.484638191301, 11506.482208300604, 11512.827307590393, 11520.135989800756, 11248.26886252391, 11326.830097548544, 11331.320120792696, 11350.20548561601, 11358.318500889973, 10730.946345268301, 10779.191560632984, 10779.892201077968, 10780.40707761073, 10799.892001079967, 46769.53230467686, 47157.0, 47210.76394618005, 47228.05543889103, 47275.29087063697, 3511.6137224905497, 3680.9815950920342, 3818.59904710007, 3899.395593682955, 4096.160287141159, 35821.64178358209, 35837.28325433477, 35876.641233587594, 35929.281414371566, 36197.0, 4746.691465054761, 4800.0, 4827.806887724487, 4908.410990681105, 4937.382827146606, 373856.0, 374376.0, 374423.0, 375041.0, 375691.0, 52700.47299527032, 52844.47155528473, 53200.73399632977, 53290.467095329324, 53842.0, 40287.39568906469, 40959.590404096176, 41232.587674123475, 42779.0, 42926.92682682895, 15893.0, 16051.117188554741, 16052.438164664181, 16054.75917861233, 16079.196040197943, 11297.830532542019, 11697.649070527897, 11832.585859494877, 11922.0, 11991.400429978466, 21400.678989815166, 21471.0, 21486.78513214879, 21529.354119376443, 21660.45848853791, 7467.615189013424, 7531.0, 7685.154632990367, 7919.920800792033, 7933.484323518953, 1022.5960745505483, 1092.415557676639, 1141.4406940599047, 1152.0347303123565, 1163.2787671643466, 35679.28641427209, 35960.184559086774, 36047.4592881107, 36130.09674758152, 37897.62102378941, 1012.9465356029793, 1103.5585765693786, 1162.7965056166827, 1165.8076417391035, 1179.3690375648987, 17149.399771008204, 17923.820761792216, 18113.094345282683, 18172.9091354545, 18206.0, 665494.0, 668360.0, 669998.0, 670333.0, 671004.0, 64912.350876491575, 65600.34399656038, 65694.0, 65787.67106164439, 65887.67056164688, 1284.4926254129566, 1290.7095903421655, 1300.1678925487695, 1340.2025794652166, 1462.4150339863927, 33970.8301458501, 35991.64008359988, 36444.0, 36740.44889326716, 36753.63246367502, 55360.0, 66035.66982165059, 67460.66269668621, 67636.0, 67759.3224067753, 555578.0, 555932.0, 556891.0, 557054.0, 564948.0, 31551.0, 31874.681253187173, 31975.200619984687, 33598.49602255919, 49080.300583468255, 53714.194287084945, 54157.72921135369, 54302.35108780173, 55435.16847247375, 56011.719941400035, 45154.322685160405, 45744.0, 45795.0, 46013.53986460093, 46979.06041879075, 45080.0, 45162.548374515834, 45824.78728447791, 46498.0, 47070.76464617655, 45181.484917182264, 45393.77303113463, 46573.06853862836, 46593.0, 46945.765271173426, 37369.030865702145, 40257.597424025385, 40940.79529602333, 41193.58806411897, 41347.793261033505, 29744.244376517858, 29906.401871962873, 30373.0, 30658.540121898637, 30716.0], "value": 22100.168529759732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.3500671382149, 407.8980254936267, 408.94274801527786, 413.8965258685329, 429.78510744627687, 16521.75217371739, 20100.296489622866, 20133.899330503362, 20148.294809681647, 20361.59276814465, 19305.32431364899, 19794.0, 20029.59940801187, 20244.39266821994, 20463.590728185427, 5349.7592608332525, 5881.353051164358, 5914.083317085859, 5940.910886336708, 5979.342272350038, 4361.214981303364, 6451.2903580606335, 6745.460363170959, 6810.455163586926, 6886.827829304258, 5794.855128621797, 6040.607360521552, 6815.7955261341995, 6826.726930922782, 6843.692033858463, 6076.848078798043, 6115.327313995457, 6118.449339559461, 6121.173641558399, 6126.632402055869, 5771.942280577183, 5923.318818335861, 5973.671448070354, 5978.641281523122, 5993.340732519419, 15096.622584435367, 15289.388424463119, 18724.719129213074, 25273.62089568658, 25464.61803072937, 2707.093123803528, 3035.5294929285997, 3146.590943177372, 3161.8893338733033, 3164.114048066545, 11148.554057837677, 14725.705485890223, 14776.630584235372, 14997.100173989596, 15891.60270993223, 2046.8260197883199, 2088.6553718636405, 2092.7697953225133, 2187.7155969723995, 2219.2454565447824, 79207.0, 88020.55989720074, 89603.55198224033, 90398.54800726021, 91704.0, 11502.597409090642, 11542.826857597143, 11563.76872462546, 11744.236624619543, 11891.702707432383, 16472.0, 16538.917305413397, 16585.17074146312, 16590.917045414695, 16623.418180363627, 7848.254415830487, 8005.319547838499, 8162.83674326522, 8227.218414250636, 8240.917590824134, 7580.317771400601, 7593.126790419062, 7608.277213664693, 7724.0, 7789.182135875765, 4935.383077115361, 5057.393112826411, 5080.796768129308, 5103.923441148386, 5167.199084141964, 1802.1367354126799, 1943.2712732725229, 2101.663733802615, 2105.705201271823, 2128.5636444528836, 908.8591268353416, 952.6141912525438, 978.1294647763497, 1020.2552136940076, 1086.6468397770757, 20697.551171418156, 20885.582288354148, 21176.258830940842, 21338.679919801216, 21374.78625213759, 1040.105509262034, 1050.2565740870118, 1055.5566662001977, 1077.6314081117093, 1104.0339702760084, 10913.39976301311, 11922.761544769057, 11958.0, 12018.218815776947, 12108.878911210775, 418835.0, 424980.0, 425921.0, 510568.0, 564965.0, 63063.369366306666, 97340.0, 98576.50711746396, 98935.0, 99466.0, 1041.8280983637615, 1052.6894566103017, 1067.4182570499097, 1078.3529882070695, 1083.7019819549844, 18884.15021324071, 19220.903895480435, 19252.133653985326, 19292.32476863303, 19375.903120484872, 45747.77126114348, 46437.303440447744, 46510.30234546552, 46519.0, 47350.0, 65582.34417655953, 65866.0, 65869.67065164643, 66367.33632663805, 67210.32789672236, 8979.281657467256, 9083.954580227057, 9137.451752894847, 9303.16271535538, 9425.0, 8235.423520353517, 13268.071235013456, 13623.114497557626, 13774.311284435938, 13940.860591393955, 12070.879291206975, 12223.69440763988, 12237.265764054186, 12418.37908104573, 12495.687607809878, 11156.55373785072, 11886.108541859569, 12203.877961220274, 12209.267443953391, 12306.138570299992, 12363.330950321591, 12369.403726214796, 12398.5660501882, 12687.11190216676, 13037.217766934014, 7824.92175078242, 7980.640871160927, 8241.351729653974, 8300.792480187802, 9286.907130928605, 11851.52593896269, 12212.08409369283, 12232.694182645506, 12511.249325040526, 12606.684832879251], "value": 9760.400319290002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.6868123353481, 1519.7492413751731, 1699.796024477063, 1871.6350311689225, 1899.4111825334148, 9353.438793672376, 9994.500274986258, 10093.54579043943, 10110.443925584099, 10119.241056920733, 8593.871091933626, 9305.11601397866, 9523.761905952355, 9554.23566114711, 9866.112049915506, 2560.705518865326, 2594.338443696864, 2600.388908606476, 2601.0376160820524, 2602.3364042169314, 2535.936601584957, 2576.935576610581, 2597.766201041906, 2598.5842265237566, 2599.337169021897, 2565.7690807827294, 2569.5888657814753, 2590.391258054365, 2600.557905156128, 2604.622329762183, 2492.8628925409093, 2498.71264804548, 2509.77412032917, 2528.3173543143434, 2542.1102614085107, 2493.6508888755407, 2503.311589312935, 2512.5351809915223, 2522.1928982725535, 2524.6465494830736, 10036.548355324028, 10105.0, 10123.1901447884, 10123.443210623413, 10130.037646423578, 5871.911921321183, 6029.0353543433275, 6030.396960303979, 6036.3360030396625, 6046.879062418727, 3677.2461645362637, 3730.1607138393915, 3849.2878817418587, 3860.826262818152, 3877.9806100969313, 1241.3979220078254, 1274.7769140400485, 1288.8195652608642, 1289.374653293152, 1316.4141956829214, 13819.963502737235, 13900.93049534756, 13906.93046534771, 13936.790948135786, 13949.16305021702, 5018.146915024455, 5202.4277329493725, 5296.364436267596, 5306.09796334624, 5370.3287089113865, 11545.134114941413, 11559.76880462386, 11583.710407239718, 11584.594539191088, 11593.82609260845, 5930.229070220844, 5939.4951429129005, 5940.34656187819, 5946.732397042144, 5948.643081415129, 4757.548032936866, 4802.73584952831, 5321.707306098125, 5412.56699464042, 5440.863978400572, 4397.956020439755, 4430.800613972379, 4493.573110554492, 4519.7740112994215, 4536.637069034469, 1402.102654301248, 1404.445244128584, 1417.2134465371817, 1424.302091974925, 1433.7419264532275, 986.7039888033561, 1022.4274406332478, 1077.5528155815248, 1079.3308148947704, 1093.092733031582, 6475.805725828232, 6524.836879078061, 6791.0, 6832.624205668736, 6961.852928504545, 494.5894907226993, 500.13726322094095, 504.53330669130906, 506.7896822818489, 516.8423630792569, 4585.564371384713, 4647.581717645428, 4653.418322709661, 4762.14281429346, 4786.305985632064, 12098.274103553815, 12394.380280986095, 12527.624171274876, 12535.0, 12579.119461637598, 11338.376389298503, 11427.714307142389, 11445.370504622158, 11713.414329283502, 12438.0, 8629.223369896614, 8857.981332146757, 8886.066962968924, 9034.141756532987, 9044.231240344645, 6569.178852643419, 6777.152855893013, 6780.99019729495, 6832.760853370109, 6863.142107236596, 10619.1504679627, 10717.785644287227, 10885.782284354427, 11254.38100904458, 11476.139289553485, 10252.64115755945, 10608.575656973635, 11175.385353805294, 11336.829947550958, 11736.647900562997, 9053.864192036994, 9210.907890921006, 9330.253579713471, 9397.624095036123, 9588.76028099303, 9300.348975571917, 9769.706908792748, 9834.655787047419, 9846.556904939438, 9853.06395892389, 8376.078631350425, 8425.70510032146, 8502.74491765248, 8556.74329770108, 8609.526476043879, 7977.5612341321785, 8062.717804876801, 8325.791855203668, 8350.665973360998, 8516.531590762568, 7896.644650990834, 7901.17037711032, 7937.484063535851, 7965.004374453193, 7972.681092756457, 5649.802256920988, 5710.086386178192, 5762.971185144215, 5796.59423840344, 5849.912251316318, 5786.103154011135, 5820.796272130455, 5842.6561890764515, 5877.382874798084, 5899.351071382059], "value": 5075.035750495836, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.8091071276867, 363.2408266722549, 364.82488405565334, 364.86499995001856, 369.2872755581726, 5606.719664016803, 5610.579206559509, 5617.354004289513, 5618.2415373924505, 5636.436356364362, 5381.354237491507, 5479.917801232975, 5549.112142057262, 5584.581156413265, 5590.496855283024, 4351.5648435156545, 4360.171567402183, 4396.406485124515, 4403.823847046115, 4408.294672852344, 4336.3278691802825, 4344.435223420951, 4374.4313239278845, 4380.8685739427865, 4390.5609439056, 4336.761478118702, 4348.826046958118, 4363.54182810805, 4368.759718215497, 4377.409049778287, 4299.161663475624, 4327.242732521796, 4352.7823608819435, 4354.1277250782, 4356.150550642626, 3897.162110146304, 3912.902177445558, 3914.706397020235, 3916.4516967624554, 3937.3503371943593, 5370.355557333145, 5421.0, 5464.890702185974, 5472.644278121909, 5473.8357849264585, 4863.513648635108, 4999.075355987934, 5037.269595908573, 5039.672421292605, 5070.771815268322, 5781.913271300934, 5831.504322132624, 5832.1834943108, 5837.445442682938, 5855.092460668603, 4351.608355248011, 4357.891052723676, 4371.300591905312, 4372.125574885035, 4376.452943382077, 5723.627964182315, 5731.770729170828, 5932.288125424933, 6037.939620603782, 6061.575689701723, 4392.165488557164, 4393.560643935581, 4394.12117576486, 4396.868093957187, 4403.3174857897075, 4459.0858873930765, 4466.3747075409465, 4467.195904737114, 4476.306172543196, 4477.462704475483, 4615.530691080256, 4839.733814640158, 4996.325496057985, 5020.497950205024, 5033.84898453047, 5674.0, 5675.290588676416, 5689.34572524165, 5714.685692286964, 5716.914246286309, 1487.293535570617, 1514.5611668914355, 1514.7500662390803, 1523.0785374848058, 1525.2983627531237, 948.9620415183454, 1003.8644782954353, 1007.1539906478594, 1011.5245834457793, 1025.963776585654, 4995.575376093073, 5002.399712034507, 5009.849704508871, 5040.571551418097, 5058.418281897629, 5226.529612334908, 5244.5279924806955, 5247.370315562157, 5249.370075590953, 5264.763085661154, 4790.682562295397, 4796.4891059316205, 4947.356843610308, 4968.527989840959, 4984.850454486371, 2806.733360330765, 2810.170999555136, 2813.043565187846, 2816.605675205473, 2819.2951762059483, 5412.188171774186, 5420.6691122817565, 5426.294581704353, 5427.83716488506, 5472.808451704172, 5000.87480316933, 5240.580753539708, 5255.39562950258, 5269.657472264291, 5334.998127107005, 5357.732113394315, 5362.195670649434, 5386.973065134649, 5398.055340315507, 5403.081476149064, 5246.842594722164, 5255.421903590678, 5275.762590683506, 5302.787888484418, 5312.654677445954, 5501.367342755474, 5907.763689452374, 5929.6145750526075, 5935.77408471823, 5937.168796368598, 5895.882082358243, 5974.671393073423, 6126.509879209563, 6166.81499555014, 6195.690215489118, 4252.234597772431, 4301.07526881722, 4308.676849236257, 4326.545712700247, 4328.112736888867, 6043.184170137151, 6047.213862197887, 6047.518358002318, 6051.33435322123, 6056.939430605814, 5676.290463692038, 5694.772209111589, 5739.053056245672, 5762.337331206965, 5770.336411312753, 5749.770009199585, 5779.335376431764, 5784.942150578609, 5795.188673585617, 5888.499477544286, 5477.835664930059, 5725.914111288251, 5725.942740572541, 5747.19539264495, 5811.331696854916, 5818.458108601139, 5834.912476312944, 5874.735636896265, 5881.735321910439, 5885.675722962299, 5733.827985160452, 5750.706091129462, 5776.277965254343, 5789.1605717171615, 5790.623609465371], "value": 4346.144140567255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.7488803582855, 793.8015496125969, 861.014138811061, 876.9429987050842, 902.3593248793359, 16296.0, 17624.82375176248, 17651.382201622913, 17660.911695441522, 17664.29342826287, 15985.120818354986, 16268.593285167877, 16283.430079947204, 16283.918580407111, 16292.674146517062, 726.2664708644281, 753.5102183580661, 755.2145768400857, 756.8334966307431, 853.4068822168596, 803.7146812881431, 877.5261358866204, 884.2969838978028, 884.7080463447079, 885.402353411448, 937.5921474158729, 939.2579861909118, 939.6570251858057, 940.4780346907463, 940.5156344482597, 773.2036002916948, 937.3485427627801, 937.8358787212211, 938.0431959401373, 938.6057855700589, 671.788386658206, 686.36168363422, 686.7321744519642, 686.9003994420831, 688.2876223109108, 15136.0, 15153.393864245418, 15179.772303415457, 15199.544013679608, 15209.923950380287, 13496.932515337361, 14509.0, 15212.239388030554, 15295.235238238043, 15471.381144754196, 14337.71324573513, 14346.497872574515, 14434.278286085653, 14759.704805903822, 14864.479743208936, 4700.694454860423, 4811.59101476375, 4911.729854857981, 4930.827421040282, 4931.90136197274, 88864.0, 88879.0, 88884.55557722235, 88989.0, 89054.55472722594, 11322.94338528302, 11650.126240531825, 11650.53397864093, 11921.46353414081, 12441.377931103409, 21977.23079692203, 22022.449438763837, 22025.118995240333, 22066.558668826532, 22068.448288792588, 9135.223506002065, 9239.953800230956, 9298.256139508823, 9519.23846092311, 9546.92637118305, 5183.792648294101, 5324.094903866352, 5364.329458817648, 5425.213344065089, 5511.614187006871, 3777.792221427848, 3840.1935593525113, 3882.5729169791302, 3889.5527014393706, 3893.7858417787293, 654.8231977366084, 664.1697877652933, 667.6995352091578, 668.6556423441931, 695.5687473766196, 687.6802286936498, 703.3353480960545, 730.5799165479871, 753.1338960195818, 781.2109769133216, 9711.223102151953, 10098.192144628412, 10274.794504109876, 10367.349794362091, 10653.733656658645, 643.2955913274957, 644.9000404937242, 645.3481983197005, 648.6293489903605, 694.4964900447236, 5145.15105007677, 5216.9739151304, 5217.739113044333, 5608.747606357723, 5657.010023245915, 119269.0, 119891.40054299847, 120684.0, 122891.0, 124138.0, 105356.0, 107522.46238768913, 108527.0, 108682.45658771656, 111005.0, 97305.0, 97392.0, 97432.0, 97778.0, 100040.49979750055, 9649.710508684751, 9904.603815847573, 10164.0, 10197.9490102549, 10264.281500294908, 54344.0, 55064.72467637636, 55408.0, 56649.150262746916, 58155.0, 47678.52321476741, 59157.70421148039, 79389.60305198438, 81261.59369203117, 82171.58914205391, 11764.117691173025, 12090.939545302217, 12092.153549251469, 12160.45277962476, 12324.568640097554, 14153.787693184817, 15244.77132842986, 15540.0, 15740.370385184464, 15799.763003554726, 15421.383144674088, 15464.14947177871, 16520.173991300147, 16627.3349066036, 16662.50012499627, 15359.769603456181, 15500.0, 15571.688566228839, 16694.833051669328, 16843.747343789608, 15282.388704452142, 15309.23453827282, 16206.270717817963, 16510.66978660396, 16513.587160320607, 11998.940005299917, 12159.148859580098, 12185.69535761613, 12726.618201453972, 12832.358382080673, 12913.806292905427, 13041.282729449591, 13132.34338283063, 13157.53948611794, 13389.732205355644], "value": 8181.5676551182205, "unit": "score"}], "name": "dromaeo_css", "value": 7757.1306207035495, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 112.0, 110.0, 109.0, 111.0, 111.0, 110.0, 111.0, 110.0, 110.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 118.0, 178.0, 115.0, 123.0, 153.0, 116.0, 143.0, 120.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 160.0, 163.0, 162.0, 159.0, 158.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 110.0, 76.0, 77.0, 75.0, 75.0, 108.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 132.0, 83.0, 83.0, 84.0, 119.0, 82.0, 83.0, 137.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 51.0, 49.0, 49.0, 51.0, 51.0, 50.0, 50.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 70.0, 58.0, 59.0, 59.0, 60.0, 58.0, 58.0, 59.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 80.0, 72.0, 72.0, 73.0, 71.0, 80.0, 72.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 124.0, 120.0, 116.0, 115.0, 116.0, 125.0, 152.0, 120.0], "value": 122.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 158.0, 157.0, 149.0, 151.0, 150.0, 149.0, 157.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 61.0, 63.0, 60.0, 62.0, 61.0, 62.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1458.3000000000004, "alertThreshold": 2.0}]} 03:45:48 INFO - Return code: 0 03:45:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:45:48 INFO - # TBPL SUCCESS # 03:45:48 INFO - Running post-action listener: _resource_record_post_action 03:45:48 INFO - [mozharness: 2016-09-03 10:45:48.403887Z] Finished run-tests step (success) 03:45:48 INFO - Running post-run listener: _resource_record_post_run 03:45:48 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 827392; Write bytes: 326373376; Read time: 160; Write time: 609256 03:45:48 INFO - TinderboxPrint: CPU usage
13.0% 03:45:48 INFO - TinderboxPrint: I/O read bytes / time
827,392 / 160 03:45:48 INFO - TinderboxPrint: I/O write bytes / time
326,373,376 / 609,256 03:45:48 INFO - TinderboxPrint: CPU idle
8,290.6 (87.0%) 03:45:48 INFO - TinderboxPrint: CPU user
1,188.3 (12.5%) 03:45:48 INFO - TinderboxPrint: Swap in / out
0 / 0 03:45:48 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 78675968; Read time: 0; Write time: 99880 03:45:48 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 827392; Write bytes: 247697408; Read time: 160; Write time: 509376 03:45:49 INFO - Running post-run listener: _upload_blobber_files 03:45:49 INFO - Blob upload gear active. 03:45:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:45:49 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 03:45:49 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'] 03:45:49 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 03:45:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:45:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:45:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:45:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:53 INFO - (blobuploader) - INFO - Done attempting. 03:45:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:45:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:45:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:54 INFO - (blobuploader) - INFO - Done attempting. 03:45:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:45:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:45:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:54 INFO - (blobuploader) - INFO - Done attempting. 03:45:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:45:54 INFO - Return code: 0 03:45:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:45:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:45:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab594f254cf520e77434ca91ea19cee2bee589a4fe20ea1a40ad00a7c34bc1868b1d9c26d653b8e94982b7a65e3ae04b7556031a79d35cab042c1e4a169ed9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/455202b48bd1c4ab9eb1c6b8eb1f7d2159a2b3f9ec03452b8aa48a7ba656f1708b28b84b42c4d6a7d3820db889715cc1830324805da1518f071f3aa99e61e1e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/467214c2d5d55a50eedcae5d267daa02fe876f16fd90995db38cab58f3b373081128f154d3df517a07da93629111c7385ec75e0d8073aaea71b17782de14982c"} 03:45:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:45:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:45:54 INFO - Contents: 03:45:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6ab594f254cf520e77434ca91ea19cee2bee589a4fe20ea1a40ad00a7c34bc1868b1d9c26d653b8e94982b7a65e3ae04b7556031a79d35cab042c1e4a169ed9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/455202b48bd1c4ab9eb1c6b8eb1f7d2159a2b3f9ec03452b8aa48a7ba656f1708b28b84b42c4d6a7d3820db889715cc1830324805da1518f071f3aa99e61e1e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/467214c2d5d55a50eedcae5d267daa02fe876f16fd90995db38cab58f3b373081128f154d3df517a07da93629111c7385ec75e0d8073aaea71b17782de14982c"} 03:45:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:45:54 INFO - Copying logs to upload dir... 03:45:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:45:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.149506 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-09-03 03:45:54.922817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:54.926061) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 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/6ab594f254cf520e77434ca91ea19cee2bee589a4fe20ea1a40ad00a7c34bc1868b1d9c26d653b8e94982b7a65e3ae04b7556031a79d35cab042c1e4a169ed9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/455202b48bd1c4ab9eb1c6b8eb1f7d2159a2b3f9ec03452b8aa48a7ba656f1708b28b84b42c4d6a7d3820db889715cc1830324805da1518f071f3aa99e61e1e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/467214c2d5d55a50eedcae5d267daa02fe876f16fd90995db38cab58f3b373081128f154d3df517a07da93629111c7385ec75e0d8073aaea71b17782de14982c"} build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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/6ab594f254cf520e77434ca91ea19cee2bee589a4fe20ea1a40ad00a7c34bc1868b1d9c26d653b8e94982b7a65e3ae04b7556031a79d35cab042c1e4a169ed9f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/455202b48bd1c4ab9eb1c6b8eb1f7d2159a2b3f9ec03452b8aa48a7ba656f1708b28b84b42c4d6a7d3820db889715cc1830324805da1518f071f3aa99e61e1e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/467214c2d5d55a50eedcae5d267daa02fe876f16fd90995db38cab58f3b373081128f154d3df517a07da93629111c7385ec75e0d8073aaea71b17782de14982c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:54.955277) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:54.956069) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1472897871.528428-210983059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:55.038210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:45:55.038994) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:45:55.039758) ========= ========= Total master_lag: 0.19 =========