builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1471260771.14 results: success (0) buildid: 20160815012516 builduid: d1661f2f04924304abc0bbee7dae6820 revision: 0463f6653d4830bb793fb43678161dc08b827389 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.142954) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.143411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.143700) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.177241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.177506) ========= 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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-15 04:32:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.8M=0s 2016-08-15 04:32:51 (30.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087351 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.287342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.287640) ========= 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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019675 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.342450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:51.342766) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0463f6653d4830bb793fb43678161dc08b827389 --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 0463f6653d4830bb793fb43678161dc08b827389 --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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-15 04:32:51,493 truncating revision to first 12 chars 2016-08-15 04:32:51,493 Setting DEBUG logging. 2016-08-15 04:32:51,493 attempt 1/10 2016-08-15 04:32:51,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0463f6653d48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 04:32:51,895 unpacking tar archive at: mozilla-aurora-0463f6653d48/testing/mozharness/ program finished with exit code 0 elapsedTime=0.745683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:52.104637) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:52.104929) ========= script_repo_revision: 0463f6653d4830bb793fb43678161dc08b827389 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:52.105330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:52.105610) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 04:32:52.115260) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-15 04:32:52.115552) ========= /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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:32:52 INFO - MultiFileLogger online at 20160815 04:32:52 in /builds/slave/test 04:32:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:32:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:32:52 INFO - {'append_to_log': False, 04:32:52 INFO - 'base_work_dir': '/builds/slave/test', 04:32:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:32:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:32:52 INFO - 'branch': 'Mozilla-Aurora', 04:32:52 INFO - 'buildbot_json_path': 'buildprops.json', 04:32:52 INFO - 'config_files': ('talos/linux_config.py',), 04:32:52 INFO - 'default_actions': ('clobber', 04:32:52 INFO - 'read-buildbot-config', 04:32:52 INFO - 'download-and-extract', 04:32:52 INFO - 'populate-webroot', 04:32:52 INFO - 'create-virtualenv', 04:32:52 INFO - 'install', 04:32:52 INFO - 'run-tests'), 04:32:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:32:52 INFO - 'download_minidump_stackwalk': True, 04:32:52 INFO - 'download_symbols': 'ondemand', 04:32:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:32:52 INFO - 'tooltool.py': '/tools/tooltool.py', 04:32:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:32:52 INFO - '/tools/misc-python/virtualenv.py')}, 04:32:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:32:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:32:52 INFO - 'installer_path': 'installer.exe', 04:32:52 INFO - 'log_level': 'info', 04:32:52 INFO - 'log_name': 'talos', 04:32:52 INFO - 'log_to_console': True, 04:32:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:32:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:32:52 INFO - 'opt_config_files': (), 04:32:52 INFO - 'pip_index': False, 04:32:52 INFO - 'sps_profile': False, 04:32:52 INFO - 'sps_profile_interval': 0, 04:32:52 INFO - 'suite': 'dromaeojs', 04:32:52 INFO - 'system_bits': '32', 04:32:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:32:52 INFO - 'title': 'talos-linux64-ix-025', 04:32:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:32:52 INFO - 'use_talos_json': True, 04:32:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:32:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:32:52 INFO - 'work_dir': 'build'} 04:32:52 INFO - [mozharness: 2016-08-15 11:32:52.350427Z] Running clobber step. 04:32:52 INFO - Running pre-action listener: _resource_record_pre_action 04:32:52 INFO - Running main action method: clobber 04:32:52 INFO - rmtree: /builds/slave/test/build 04:32:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:32:53 INFO - Running post-action listener: _resource_record_post_action 04:32:53 INFO - [mozharness: 2016-08-15 11:32:53.299572Z] Finished clobber step (success) 04:32:53 INFO - [mozharness: 2016-08-15 11:32:53.299671Z] Running read-buildbot-config step. 04:32:53 INFO - Running pre-action listener: _resource_record_pre_action 04:32:53 INFO - Running main action method: read_buildbot_config 04:32:53 INFO - Using buildbot properties: 04:32:53 INFO - { 04:32:53 INFO - "project": "", 04:32:53 INFO - "product": "firefox", 04:32:53 INFO - "script_repo_revision": "production", 04:32:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:32:53 INFO - "repository": "", 04:32:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:32:53 INFO - "buildid": "20160815012516", 04:32:53 INFO - "pgo_build": "True", 04:32:53 INFO - "basedir": "/builds/slave/test", 04:32:53 INFO - "buildnumber": 468, 04:32:53 INFO - "slavename": "talos-linux64-ix-025", 04:32:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:32:53 INFO - "platform": "ubuntu64_hw", 04:32:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:32:53 INFO - "branch": "mozilla-aurora", 04:32:53 INFO - "repo_path": "releases/mozilla-aurora", 04:32:53 INFO - "revision": "0463f6653d4830bb793fb43678161dc08b827389", 04:32:53 INFO - "stage_platform": "linux64-pgo", 04:32:53 INFO - "builduid": "d1661f2f04924304abc0bbee7dae6820", 04:32:53 INFO - "slavebuilddir": "test" 04:32:53 INFO - } 04:32:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:32:53 INFO - Running post-action listener: _resource_record_post_action 04:32:53 INFO - [mozharness: 2016-08-15 11:32:53.302094Z] Finished read-buildbot-config step (success) 04:32:53 INFO - [mozharness: 2016-08-15 11:32:53.302182Z] Running download-and-extract step. 04:32:53 INFO - Running pre-action listener: _resource_record_pre_action 04:32:53 INFO - Running main action method: download_and_extract 04:32:53 INFO - mkdir: /builds/slave/test/build/tests 04:32: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')]} 04:32:53 INFO - https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:32:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:32:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:32:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:32:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:32:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:32:53 INFO - retry: Failed, sleeping 30 seconds before retrying 04:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:33:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:33:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:33:23 INFO - retry: Failed, sleeping 60 seconds before retrying 04:34:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:34:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:34:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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! 04:34:23 INFO - Caught exception: HTTP Error 404: Not Found 04:34:23 INFO - Caught exception: HTTP Error 404: Not Found 04:34:23 INFO - Caught exception: HTTP Error 404: Not Found 04:34:23 INFO - trying https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:34:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:23 INFO - Downloaded 1361 bytes. 04:34:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:34:23 INFO - Using the following test package requirements: 04:34:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:34:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:34:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:34:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'jsshell-linux-x86_64.zip'], 04:34:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:34:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:34:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:34:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:34:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:34:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:34:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:34:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:34:23 INFO - https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:34:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:34:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:34:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:34:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:34:23 INFO - retry: Failed, sleeping 30 seconds before retrying 04:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:34:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:34:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:34:54 INFO - retry: Failed, sleeping 60 seconds before retrying 04:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:35:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:35:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:35:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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! 04:35:54 INFO - Caught exception: HTTP Error 404: Not Found 04:35:54 INFO - Caught exception: HTTP Error 404: Not Found 04:35:54 INFO - Caught exception: HTTP Error 404: Not Found 04:35:54 INFO - trying https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:35:55 INFO - Downloaded 21766161 bytes. 04:35: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'] 04:35: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 04:35:56 INFO - Return code: 0 04:35:56 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 04:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:35:56 INFO - https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:35:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:35:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:35:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:35:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:35:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:35:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:35:56 INFO - retry: Failed, sleeping 30 seconds before retrying 04:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:36:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:36:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:36:26 INFO - retry: Failed, sleeping 60 seconds before retrying 04:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:37:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:37:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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! 04:37:26 INFO - Caught exception: HTTP Error 404: Not Found 04:37:26 INFO - Caught exception: HTTP Error 404: Not Found 04:37:26 INFO - Caught exception: HTTP Error 404: Not Found 04:37:26 INFO - trying https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:37:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:27 INFO - Downloaded 21766161 bytes. 04:37:27 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'] 04:37:27 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 04:37:29 INFO - Return code: 0 04:37: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')]} 04:37:29 INFO - https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:37:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:37:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:37:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:37:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:37:29 INFO - retry: Failed, sleeping 30 seconds before retrying 04:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:37:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:37:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:37:59 INFO - retry: Failed, sleeping 60 seconds before retrying 04:38:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:38:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:38:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:38:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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! 04:38:59 INFO - Caught exception: HTTP Error 404: Not Found 04:38:59 INFO - Caught exception: HTTP Error 404: Not Found 04:38:59 INFO - Caught exception: HTTP Error 404: Not Found 04:38:59 INFO - trying https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:38:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:38:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:39:00 INFO - Downloaded 14413723 bytes. 04:39: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'] 04:39: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 04:39:00 INFO - Return code: 0 04:39: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')]} 04:39:00 INFO - https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:39:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:39:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:39:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:39:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:39:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:39:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:39:00 INFO - retry: Failed, sleeping 30 seconds before retrying 04:39:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:39:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:39:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:39:30 INFO - retry: Failed, sleeping 60 seconds before retrying 04:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:40:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:40:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:40:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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! 04:40:30 INFO - Caught exception: HTTP Error 404: Not Found 04:40:30 INFO - Caught exception: HTTP Error 404: Not Found 04:40:30 INFO - Caught exception: HTTP Error 404: Not Found 04:40:30 INFO - trying https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:40:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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 04:40:33 INFO - Downloaded 63772588 bytes. 04:40:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:40:33 INFO - mkdir: /builds/slave/test/properties 04:40:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:40:33 INFO - Writing to file /builds/slave/test/properties/build_url 04:40:33 INFO - Contents: 04:40:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:40:33 INFO - Running post-action listener: _resource_record_post_action 04:40:34 INFO - Running post-action listener: set_extra_try_arguments 04:40:34 INFO - [mozharness: 2016-08-15 11:40:34.000461Z] Finished download-and-extract step (success) 04:40:34 INFO - [mozharness: 2016-08-15 11:40:34.000693Z] Running populate-webroot step. 04:40:34 INFO - Running pre-action listener: _resource_record_pre_action 04:40:34 INFO - Running main action method: populate_webroot 04:40:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:40:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:40:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:40:34 INFO - '--tppagecycles', 04:40:34 INFO - '7'], 04:40:34 INFO - 'tests': ['tsvgm']}}, 04:40:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:40:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:40:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:40:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:40:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:40:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['damp', 'tps']}, 04:40:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'tests': ['damp', 'tps']}, 04:40:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['dromaeo_dom']}, 04:40:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:40:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['basic_compositor_video']}, 04:40:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:40:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'other-e10s': {'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['a11yr', 04:40:34 INFO - 'ts_paint', 04:40:34 INFO - 'tpaint', 04:40:34 INFO - 'sessionrestore', 04:40:34 INFO - 'sessionrestore_no_auto_restore', 04:40:34 INFO - 'tabpaint']}, 04:40:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['tsvgx', 04:40:34 INFO - 'tsvgr_opacity', 04:40:34 INFO - 'tart', 04:40:34 INFO - 'tscrollx', 04:40:34 INFO - 'cart']}, 04:40:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:40:34 INFO - 'tsvgr_opacity', 04:40:34 INFO - 'tart', 04:40:34 INFO - 'tscrollx', 04:40:34 INFO - 'cart']}, 04:40:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'talos_options': ['--disable-e10s'], 04:40:34 INFO - 'tests': ['tp5o']}, 04:40:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'tests': ['tp5o']}, 04:40:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'talos_options': ['--disable-e10s', 04:40:34 INFO - '--xperf_path', 04:40:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:34 INFO - 'tests': ['tp5n']}, 04:40:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:40:34 INFO - 'talos_options': ['--xperf_path', 04:40:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:40:34 INFO - 'tests': ['tp5n']}}, 04:40:34 INFO - 'talos.zip': {'path': '', 04:40:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:40:34 INFO - Running post-action listener: _resource_record_post_action 04:40:34 INFO - [mozharness: 2016-08-15 11:40:34.021719Z] Finished populate-webroot step (success) 04:40:34 INFO - [mozharness: 2016-08-15 11:40:34.021808Z] Running create-virtualenv step. 04:40:34 INFO - Running pre-action listener: _resource_record_pre_action 04:40:34 INFO - Running main action method: create_virtualenv 04:40:34 INFO - Creating virtualenv /builds/slave/test/build/venv 04:40:34 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 04:40:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:40:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:40:34 INFO - Using real prefix '/usr' 04:40:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:40:34 INFO - Installing distribute.............................................................................................................................................................................................done. 04:40:36 INFO - Installing pip.................done. 04:40:36 INFO - Return code: 0 04:40:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:40:36 INFO - Using env: {'DISPLAY': ':0', 04:40:36 INFO - 'HOME': '/home/cltbld', 04:40:36 INFO - 'LANG': 'en_US.UTF-8', 04:40:36 INFO - 'LANGUAGE': 'en_US:en', 04:40:36 INFO - 'LOGNAME': 'cltbld', 04:40:36 INFO - 'MAIL': '/var/mail/cltbld', 04:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:36 INFO - 'MOZ_NO_REMOTE': '1', 04:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:36 INFO - 'NO_EM_RESTART': '1', 04:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:36 INFO - 'PWD': '/builds/slave/test', 04:40:36 INFO - 'SHELL': '/bin/bash', 04:40:36 INFO - 'SHLVL': '1', 04:40:36 INFO - 'TERM': 'linux', 04:40:36 INFO - 'TMOUT': '86400', 04:40:36 INFO - 'USER': 'cltbld', 04:40:36 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:36 INFO - '_': '/tools/buildbot/bin/python'} 04:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:36 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 04:40:36 INFO - Cleaning up... 04:40:36 INFO - Return code: 0 04:40:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:40:36 INFO - Using env: {'DISPLAY': ':0', 04:40:36 INFO - 'HOME': '/home/cltbld', 04:40:36 INFO - 'LANG': 'en_US.UTF-8', 04:40:36 INFO - 'LANGUAGE': 'en_US:en', 04:40:36 INFO - 'LOGNAME': 'cltbld', 04:40:36 INFO - 'MAIL': '/var/mail/cltbld', 04:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:36 INFO - 'MOZ_NO_REMOTE': '1', 04:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:36 INFO - 'NO_EM_RESTART': '1', 04:40:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:36 INFO - 'PWD': '/builds/slave/test', 04:40:36 INFO - 'SHELL': '/bin/bash', 04:40:36 INFO - 'SHLVL': '1', 04:40:36 INFO - 'TERM': 'linux', 04:40:36 INFO - 'TMOUT': '86400', 04:40:36 INFO - 'USER': 'cltbld', 04:40:36 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:36 INFO - '_': '/tools/buildbot/bin/python'} 04:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:37 INFO - Downloading/unpacking psutil>=3.1.1 04:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:40:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:39 INFO - Installing collected packages: psutil 04:40:39 INFO - Running setup.py install for psutil 04:40:39 INFO - building 'psutil._psutil_linux' extension 04:40:39 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 04:40:39 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 04:40:39 INFO - building 'psutil._psutil_posix' extension 04:40:39 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 04:40:39 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 04:40:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:40:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:40:39 INFO - Successfully installed psutil 04:40:39 INFO - Cleaning up... 04:40:39 INFO - Return code: 0 04:40:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:40:39 INFO - Using env: {'DISPLAY': ':0', 04:40:39 INFO - 'HOME': '/home/cltbld', 04:40:39 INFO - 'LANG': 'en_US.UTF-8', 04:40:39 INFO - 'LANGUAGE': 'en_US:en', 04:40:39 INFO - 'LOGNAME': 'cltbld', 04:40:39 INFO - 'MAIL': '/var/mail/cltbld', 04:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:39 INFO - 'MOZ_NO_REMOTE': '1', 04:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:39 INFO - 'NO_EM_RESTART': '1', 04:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:39 INFO - 'PWD': '/builds/slave/test', 04:40:39 INFO - 'SHELL': '/bin/bash', 04:40:39 INFO - 'SHLVL': '1', 04:40:39 INFO - 'TERM': 'linux', 04:40:39 INFO - 'TMOUT': '86400', 04:40:39 INFO - 'USER': 'cltbld', 04:40:39 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:39 INFO - '_': '/tools/buildbot/bin/python'} 04:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:40:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:40:41 INFO - Installing collected packages: mozsystemmonitor 04:40:41 INFO - Running setup.py install for mozsystemmonitor 04:40:41 INFO - Successfully installed mozsystemmonitor 04:40:41 INFO - Cleaning up... 04:40:41 INFO - Return code: 0 04:40:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:40:41 INFO - Using env: {'DISPLAY': ':0', 04:40:41 INFO - 'HOME': '/home/cltbld', 04:40:41 INFO - 'LANG': 'en_US.UTF-8', 04:40:41 INFO - 'LANGUAGE': 'en_US:en', 04:40:41 INFO - 'LOGNAME': 'cltbld', 04:40:41 INFO - 'MAIL': '/var/mail/cltbld', 04:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:41 INFO - 'MOZ_NO_REMOTE': '1', 04:40:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:41 INFO - 'NO_EM_RESTART': '1', 04:40:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:41 INFO - 'PWD': '/builds/slave/test', 04:40:41 INFO - 'SHELL': '/bin/bash', 04:40:41 INFO - 'SHLVL': '1', 04:40:41 INFO - 'TERM': 'linux', 04:40:41 INFO - 'TMOUT': '86400', 04:40:41 INFO - 'USER': 'cltbld', 04:40:41 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:41 INFO - '_': '/tools/buildbot/bin/python'} 04:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:42 INFO - Downloading/unpacking jsonschema==2.5.1 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:40:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40: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 04:40:44 INFO - Downloading functools32-3.2.3-2.tar.gz 04:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:40:44 INFO - warning: no files found matching '*.txt' 04:40:44 INFO - no previously-included directories found matching 'build' 04:40:44 INFO - no previously-included directories found matching 'dist' 04:40:44 INFO - no previously-included directories found matching '.git*' 04:40:44 INFO - Installing collected packages: jsonschema, functools32 04:40:44 INFO - Running setup.py install for functools32 04:40:44 INFO - warning: no files found matching '*.txt' 04:40:44 INFO - no previously-included directories found matching 'build' 04:40:44 INFO - no previously-included directories found matching 'dist' 04:40:44 INFO - no previously-included directories found matching '.git*' 04:40:44 INFO - Successfully installed jsonschema functools32 04:40:44 INFO - Cleaning up... 04:40:44 INFO - Return code: 0 04:40:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:40:44 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')]} 04:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:44 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')]} 04:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:44 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 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:44 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 04:40:44 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 04:40:44 INFO - Using env: {'DISPLAY': ':0', 04:40:44 INFO - 'HOME': '/home/cltbld', 04:40:44 INFO - 'LANG': 'en_US.UTF-8', 04:40:44 INFO - 'LANGUAGE': 'en_US:en', 04:40:44 INFO - 'LOGNAME': 'cltbld', 04:40:44 INFO - 'MAIL': '/var/mail/cltbld', 04:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:44 INFO - 'MOZ_NO_REMOTE': '1', 04:40:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:44 INFO - 'NO_EM_RESTART': '1', 04:40:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:44 INFO - 'PWD': '/builds/slave/test', 04:40:44 INFO - 'SHELL': '/bin/bash', 04:40:44 INFO - 'SHLVL': '1', 04:40:44 INFO - 'TERM': 'linux', 04:40:44 INFO - 'TMOUT': '86400', 04:40:44 INFO - 'USER': 'cltbld', 04:40:44 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:44 INFO - '_': '/tools/buildbot/bin/python'} 04:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:40:44 INFO - Cleaning up... 04:40:44 INFO - Return code: 0 04:40:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:40:44 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')]} 04:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:44 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')]} 04:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:44 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 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:44 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 04:40:44 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 04:40:44 INFO - Using env: {'DISPLAY': ':0', 04:40:44 INFO - 'HOME': '/home/cltbld', 04:40:44 INFO - 'LANG': 'en_US.UTF-8', 04:40:44 INFO - 'LANGUAGE': 'en_US:en', 04:40:44 INFO - 'LOGNAME': 'cltbld', 04:40:44 INFO - 'MAIL': '/var/mail/cltbld', 04:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:44 INFO - 'MOZ_NO_REMOTE': '1', 04:40:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:44 INFO - 'NO_EM_RESTART': '1', 04:40:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:44 INFO - 'PWD': '/builds/slave/test', 04:40:44 INFO - 'SHELL': '/bin/bash', 04:40:44 INFO - 'SHLVL': '1', 04:40:44 INFO - 'TERM': 'linux', 04:40:44 INFO - 'TMOUT': '86400', 04:40:44 INFO - 'USER': 'cltbld', 04:40:44 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:44 INFO - '_': '/tools/buildbot/bin/python'} 04:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:46 INFO - Downloading blobuploader-1.2.4.tar.gz 04:40:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:40:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:40:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:40:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:40:47 INFO - Downloading docopt-0.6.1.tar.gz 04:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:40:47 INFO - Installing collected packages: blobuploader, requests, docopt 04:40:47 INFO - Running setup.py install for blobuploader 04:40:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:40:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:40:47 INFO - Running setup.py install for requests 04:40:47 INFO - Running setup.py install for docopt 04:40:48 INFO - Successfully installed blobuploader requests docopt 04:40:48 INFO - Cleaning up... 04:40:48 INFO - Return code: 0 04:40:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:48 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')]} 04:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:48 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')]} 04:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:48 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 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:48 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 04:40:48 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 04:40:48 INFO - Using env: {'DISPLAY': ':0', 04:40:48 INFO - 'HOME': '/home/cltbld', 04:40:48 INFO - 'LANG': 'en_US.UTF-8', 04:40:48 INFO - 'LANGUAGE': 'en_US:en', 04:40:48 INFO - 'LOGNAME': 'cltbld', 04:40:48 INFO - 'MAIL': '/var/mail/cltbld', 04:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:48 INFO - 'MOZ_NO_REMOTE': '1', 04:40:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:48 INFO - 'NO_EM_RESTART': '1', 04:40:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:48 INFO - 'PWD': '/builds/slave/test', 04:40:48 INFO - 'SHELL': '/bin/bash', 04:40:48 INFO - 'SHLVL': '1', 04:40:48 INFO - 'TERM': 'linux', 04:40:48 INFO - 'TMOUT': '86400', 04:40:48 INFO - 'USER': 'cltbld', 04:40:48 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:48 INFO - '_': '/tools/buildbot/bin/python'} 04:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:48 INFO - Running setup.py (path:/tmp/pip-Q9qfIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:48 INFO - Running setup.py (path:/tmp/pip-UwiFg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:48 INFO - Running setup.py (path:/tmp/pip-Xp2ITx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:48 INFO - Running setup.py (path:/tmp/pip-Pqzltf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:48 INFO - Running setup.py (path:/tmp/pip-6rwRON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:48 INFO - Running setup.py (path:/tmp/pip-QQko3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:48 INFO - Running setup.py (path:/tmp/pip-Gj9sy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:48 INFO - Running setup.py (path:/tmp/pip-WHAIdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:48 INFO - Running setup.py (path:/tmp/pip-vkChnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:48 INFO - Running setup.py (path:/tmp/pip-LLoHYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:49 INFO - Running setup.py (path:/tmp/pip-8HOzy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:49 INFO - Running setup.py (path:/tmp/pip-G3t166-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:49 INFO - Running setup.py (path:/tmp/pip-_OxCCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:49 INFO - Running setup.py (path:/tmp/pip-tiqfFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:49 INFO - Running setup.py (path:/tmp/pip-UlryH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:49 INFO - Running setup.py (path:/tmp/pip-HJdbo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:49 INFO - Running setup.py (path:/tmp/pip-VABzDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:40:49 INFO - Running setup.py install for manifestparser 04:40:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:40:49 INFO - Running setup.py install for mozcrash 04:40:49 INFO - Running setup.py install for mozdebug 04:40:49 INFO - Running setup.py install for mozdevice 04:40:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:40:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:40:49 INFO - Running setup.py install for mozfile 04:40:50 INFO - Running setup.py install for mozhttpd 04:40:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozinfo 04:40:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozInstall 04:40:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozleak 04:40:50 INFO - Running setup.py install for mozlog 04:40:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for moznetwork 04:40:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozprocess 04:40:50 INFO - Running setup.py install for mozprofile 04:40:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:40:50 INFO - Running setup.py install for mozrunner 04:40:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Running setup.py install for mozscreenshot 04:40:51 INFO - Running setup.py install for moztest 04:40:51 INFO - Running setup.py install for mozversion 04:40:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:40:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:40:51 INFO - Cleaning up... 04:40:51 INFO - Return code: 0 04:40:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:51 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 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:51 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 04:40:51 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 04:40:51 INFO - Using env: {'DISPLAY': ':0', 04:40:51 INFO - 'HOME': '/home/cltbld', 04:40:51 INFO - 'LANG': 'en_US.UTF-8', 04:40:51 INFO - 'LANGUAGE': 'en_US:en', 04:40:51 INFO - 'LOGNAME': 'cltbld', 04:40:51 INFO - 'MAIL': '/var/mail/cltbld', 04:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:51 INFO - 'MOZ_NO_REMOTE': '1', 04:40:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:51 INFO - 'NO_EM_RESTART': '1', 04:40:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:51 INFO - 'PWD': '/builds/slave/test', 04:40:51 INFO - 'SHELL': '/bin/bash', 04:40:51 INFO - 'SHLVL': '1', 04:40:51 INFO - 'TERM': 'linux', 04:40:51 INFO - 'TMOUT': '86400', 04:40:51 INFO - 'USER': 'cltbld', 04:40:51 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:51 INFO - '_': '/tools/buildbot/bin/python'} 04:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:40:51 INFO - Running setup.py (path:/tmp/pip-6sMDyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:40:51 INFO - Running setup.py (path:/tmp/pip-j_fpUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:40:51 INFO - Running setup.py (path:/tmp/pip-d1bfPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:40:51 INFO - Running setup.py (path:/tmp/pip-DH3YAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:40:51 INFO - Running setup.py (path:/tmp/pip-T_wtww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:40:51 INFO - Running setup.py (path:/tmp/pip-yXSBD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:40:51 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)) 04:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:40:51 INFO - Running setup.py (path:/tmp/pip-5OAlAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:40:52 INFO - Running setup.py (path:/tmp/pip-8fyPUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:40:52 INFO - Running setup.py (path:/tmp/pip-AUMRRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:40:52 INFO - Running setup.py (path:/tmp/pip-61ZPSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:40:52 INFO - Running setup.py (path:/tmp/pip-taSBg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:40:52 INFO - Running setup.py (path:/tmp/pip-yfm0mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:40:52 INFO - Running setup.py (path:/tmp/pip-LNy1ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:40:52 INFO - Running setup.py (path:/tmp/pip-ZXEcHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:52 INFO - Running setup.py (path:/tmp/pip-9uol9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:40:52 INFO - Running setup.py (path:/tmp/pip-wdZWNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:40:52 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)) 04:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:40:52 INFO - Running setup.py (path:/tmp/pip-7bCfRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:40:52 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)) 04:40:52 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)) 04:40:52 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)) 04:40:52 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)) 04:40:52 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)) 04:40:52 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)) 04:40:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:40:52 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 04:40:52 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 04:40:52 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 04:40:52 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 04:40:52 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 04:40:52 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 04:40:54 INFO - Downloading blessings-1.6.tar.gz 04:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:40:54 INFO - Installing collected packages: blessings 04:40:54 INFO - Running setup.py install for blessings 04:40:54 INFO - Successfully installed blessings 04:40:54 INFO - Cleaning up... 04:40:54 INFO - Return code: 0 04:40:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:40:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:55 INFO - Reading from file tmpfile_stdout 04:40:55 INFO - Current package versions: 04:40:55 INFO - argparse == 1.2.1 04:40:55 INFO - blessings == 1.6 04:40:55 INFO - blobuploader == 1.2.4 04:40:55 INFO - docopt == 0.6.1 04:40:55 INFO - functools32 == 3.2.3-2 04:40:55 INFO - jsonschema == 2.5.1 04:40:55 INFO - manifestparser == 1.1 04:40:55 INFO - mozInstall == 1.12 04:40:55 INFO - mozcrash == 0.17 04:40:55 INFO - mozdebug == 0.1 04:40:55 INFO - mozdevice == 0.48 04:40:55 INFO - mozfile == 1.2 04:40:55 INFO - mozhttpd == 0.7 04:40:55 INFO - mozinfo == 0.9 04:40:55 INFO - mozleak == 0.1 04:40:55 INFO - mozlog == 3.3 04:40:55 INFO - moznetwork == 0.27 04:40:55 INFO - mozprocess == 0.23 04:40:55 INFO - mozprofile == 0.28 04:40:55 INFO - mozrunner == 6.12 04:40:55 INFO - mozscreenshot == 0.1 04:40:55 INFO - mozsystemmonitor == 0.3 04:40:55 INFO - moztest == 0.7 04:40:55 INFO - mozversion == 1.4 04:40:55 INFO - psutil == 3.1.1 04:40:55 INFO - requests == 1.2.3 04:40:55 INFO - wsgiref == 0.1.2 04:40:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:40:55 INFO - Using env: {'DISPLAY': ':0', 04:40:55 INFO - 'HOME': '/home/cltbld', 04:40:55 INFO - 'LANG': 'en_US.UTF-8', 04:40:55 INFO - 'LANGUAGE': 'en_US:en', 04:40:55 INFO - 'LOGNAME': 'cltbld', 04:40:55 INFO - 'MAIL': '/var/mail/cltbld', 04:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:55 INFO - 'MOZ_NO_REMOTE': '1', 04:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:55 INFO - 'NO_EM_RESTART': '1', 04:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:55 INFO - 'PWD': '/builds/slave/test', 04:40:55 INFO - 'SHELL': '/bin/bash', 04:40:55 INFO - 'SHLVL': '1', 04:40:55 INFO - 'TERM': 'linux', 04:40:55 INFO - 'TMOUT': '86400', 04:40:55 INFO - 'USER': 'cltbld', 04:40:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:55 INFO - '_': '/tools/buildbot/bin/python'} 04:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:40:55 INFO - Cleaning up... 04:40:55 INFO - Return code: 0 04:40:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd96180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, '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': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', '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 04:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:40:55 INFO - Using env: {'DISPLAY': ':0', 04:40:55 INFO - 'HOME': '/home/cltbld', 04:40:55 INFO - 'LANG': 'en_US.UTF-8', 04:40:55 INFO - 'LANGUAGE': 'en_US:en', 04:40:55 INFO - 'LOGNAME': 'cltbld', 04:40:55 INFO - 'MAIL': '/var/mail/cltbld', 04:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:40:55 INFO - 'MOZ_NO_REMOTE': '1', 04:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:40:55 INFO - 'NO_EM_RESTART': '1', 04:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:40:55 INFO - 'PWD': '/builds/slave/test', 04:40:55 INFO - 'SHELL': '/bin/bash', 04:40:55 INFO - 'SHLVL': '1', 04:40:55 INFO - 'TERM': 'linux', 04:40:55 INFO - 'TMOUT': '86400', 04:40:55 INFO - 'USER': 'cltbld', 04:40:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:40:55 INFO - '_': '/tools/buildbot/bin/python'} 04:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:40:55 INFO - Cleaning up... 04:40:55 INFO - Return code: 0 04:40:55 INFO - Running post-action listener: _resource_record_post_action 04:40:55 INFO - Running post-action listener: _start_resource_monitoring 04:40:55 INFO - Starting resource monitoring. 04:40:55 INFO - [mozharness: 2016-08-15 11:40:55.572907Z] Finished create-virtualenv step (success) 04:40:55 INFO - [mozharness: 2016-08-15 11:40:55.573372Z] Running install step. 04:40:55 INFO - Running pre-action listener: _resource_record_pre_action 04:40:55 INFO - Running main action method: install 04:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:40:55 INFO - Reading from file tmpfile_stdout 04:40:55 INFO - Detecting whether we're running mozinstall >=1.0... 04:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:40:55 INFO - Reading from file tmpfile_stdout 04:40:55 INFO - Output received: 04:40:55 INFO - Usage: mozinstall [options] installer 04:40:55 INFO - Options: 04:40:55 INFO - -h, --help show this help message and exit 04:40:55 INFO - -d DEST, --destination=DEST 04:40:55 INFO - Directory to install application into. [default: 04:40:55 INFO - "/builds/slave/test"] 04:40:55 INFO - --app=APP Application being installed. [default: firefox] 04:40:55 INFO - mkdir: /builds/slave/test/build/application 04:40:55 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'] 04:40:55 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 04:41:12 INFO - Reading from file tmpfile_stdout 04:41:12 INFO - Output received: 04:41:12 INFO - /builds/slave/test/build/application/firefox/firefox 04:41:12 INFO - Running post-action listener: _resource_record_post_action 04:41:12 INFO - [mozharness: 2016-08-15 11:41:12.360299Z] Finished install step (success) 04:41:12 INFO - [mozharness: 2016-08-15 11:41:12.360638Z] Running run-tests step. 04:41:12 INFO - Running pre-action listener: _resource_record_pre_action 04:41:12 INFO - Running main action method: run_tests 04:41:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:41:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:41:12 INFO - Python 2.7.3 04:41:12 INFO - Return code: 0 04:41:12 INFO - grabbing minidump binary from tooltool 04:41: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')]} 04:41: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 0xd943b0>, '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 0xd952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd95740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:41: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 04:41: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 04:41: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 04:41:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:41:12 INFO - Return code: 0 04:41:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:41:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:41:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:41:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:41:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:41: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/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 04:41: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/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 04:41:12 INFO - Using env: {'DISPLAY': ':0', 04:41:12 INFO - 'HOME': '/home/cltbld', 04:41:12 INFO - 'LANG': 'en_US.UTF-8', 04:41:12 INFO - 'LANGUAGE': 'en_US:en', 04:41:12 INFO - 'LOGNAME': 'cltbld', 04:41:12 INFO - 'MAIL': '/var/mail/cltbld', 04:41:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:41:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:41:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:41:12 INFO - 'MOZ_NO_REMOTE': '1', 04:41:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:41:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:41:12 INFO - 'NO_EM_RESTART': '1', 04:41:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:41:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:41:12 INFO - 'PWD': '/builds/slave/test', 04:41:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:41:12 INFO - 'SHELL': '/bin/bash', 04:41:12 INFO - 'SHLVL': '1', 04:41:12 INFO - 'TERM': 'linux', 04:41:12 INFO - 'TMOUT': '86400', 04:41:12 INFO - 'USER': 'cltbld', 04:41:12 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990', 04:41:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:41:12 INFO - '_': '/tools/buildbot/bin/python'} 04:41: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/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 04:41:12 INFO - mozversion application_buildid: 20160815012516 04:41:12 INFO - mozversion application_changeset: 0463f6653d4830bb793fb43678161dc08b827389 04:41:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:41:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:41:12 INFO - mozversion application_name: Firefox 04:41:12 INFO - mozversion application_remotingname: firefox-dev 04:41:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:41:12 INFO - mozversion application_vendor: Mozilla 04:41:12 INFO - mozversion application_version: 50.0a2 04:41:12 INFO - mozversion platform_buildid: 20160815012516 04:41:12 INFO - mozversion platform_changeset: 0463f6653d4830bb793fb43678161dc08b827389 04:41:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:41:12 INFO - mozversion platform_version: 50.0a2 04:41:12 INFO - using testdate: 1471261272 04:41:12 INFO - actual date: 1471261272 04:41:12 INFO - starting webserver on 'localhost:41760' 04:41:12 INFO - SUITE-START | Running 2 tests 04:41:12 INFO - TEST-START | dromaeo_css 04:41:12 INFO - operating with platform_type : linux_ 04:41:12 INFO - Initialising browser for dromaeo_css test... 04:41:12 INFO - TEST-INFO | started process 23673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprzqDzr/profile http://localhost:41760/getInfo.html) 04:41:16 INFO - PROCESS | 23673 | __metrics Screen width/height:1600/1200 04:41:16 INFO - PROCESS | 23673 | colorDepth:24 04:41:16 INFO - PROCESS | 23673 | Browser inner width/height: 1024/697 04:41:16 INFO - PROCESS | 23673 | __metrics 04:41:17 INFO - PROCESS | 23673 | 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" 04:41:17 INFO - PROCESS | 23673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:41:18 INFO - TEST-INFO | 23673: exit 0 04:41:18 INFO - Browser initialized. 04:41:18 INFO - Running cycle 1/1 for dromaeo_css test... 04:41:18 INFO - TEST-INFO | started process 23762 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprzqDzr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:41:30 INFO - PROCESS | 23762 | RSS: Main: 144998400 04:41:30 INFO - PROCESS | 23762 | 04:42:21 INFO - PROCESS | 23762 | 1471261341215 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] 04:44:29 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41760/tests/dromaeo/cssquery-ext.html) 04:44:31 INFO - PROCESS | 23762 | RSS: Main: 225189888 04:44:31 INFO - PROCESS | 23762 | 04:47:33 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-ext.html (next: http://localhost:41760/tests/dromaeo/cssquery-jquery.html) 04:47:34 INFO - PROCESS | 23762 | RSS: Main: 230236160 04:47:34 INFO - PROCESS | 23762 | 04:50:36 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41760/tests/dromaeo/cssquery-mootools.html) 04:50:36 INFO - PROCESS | 23762 | RSS: Main: 186888192 04:50:36 INFO - PROCESS | 23762 | 04:53:38 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41760/tests/dromaeo/cssquery-prototype.html) 04:53:39 INFO - PROCESS | 23762 | RSS: Main: 176455680 04:53:39 INFO - PROCESS | 23762 | 04:56:42 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41760/tests/dromaeo/cssquery-yui.html) 04:56:42 INFO - PROCESS | 23762 | RSS: Main: 177995776 04:56:42 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | Cycle 1(1): loaded http://localhost:41760/tests/dromaeo/cssquery-yui.html (next: http://localhost:41760/tests/dromaeo/cssquery-dojo.html) 04:59:45 INFO - PROCESS | 23762 | RSS: Main: 266407936 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | __start_tp_report 04:59:45 INFO - PROCESS | 23762 | _x_x_mozilla_page_load 04:59:45 INFO - PROCESS | 23762 | _x_x_mozilla_page_load_details 04:59:45 INFO - PROCESS | 23762 | |i|pagename|runs| 04:59:45 INFO - PROCESS | 23762 | |0;dojo.html;3243.4810430331154;3262.3312220994712;3264.2329052672626;3278.8360581970896;3306.884259050934;5534.501894829465;5635.154726790984;5637.126245431965;5638.013347664158;5641.915371269434;5613.4386561343845;5682.17608446775;5704.600677952545;5729.0833466645345;5755.136729490579;4962.5781808546135;5073.264376665381;5168.457311982244;5200.193969934648;5255.106631872572;5152;5191.195364718475;5192.221166824968;5196.376434827807;5202.245674377232;5090.898182036375;5130.871728206806;5153.974230128844;5161.406438259611;5173.87065323368;5213.4004589472315;5222.73886305687;5240.711760853152;5255.421903590602;5272.945410917812;4971.875703107415;4979.3526841510375;4984.87537811554;5150.098732721759;5153.458886816905;5491.917621235684;5508.14623733322;5519.917201241985;5530.087535556629;5535.612507124503;5975.163477113208;6024.969875150597;6025.578209525335;6027.276726792769;6035.396460353973;6005.069214271794;6020.548458865559;6068.878622427526;6070.6357618543025;6136.96931515344;5691.004074286983;5727.713614319267;5739.82780516581;5748.540116790647;5749.626274292157;5941.7623295067715;6244.875102498016;6245.843853903598;6249.25008998923;6253.405926436982;5872.7357268923;5917;5917.55618328627;5959.433853783884;6022.759089636453;6082.148499210114;6089.117078023662;6109.725062372204;6111.938880611226;6114.296855861633;5775.797847075332;6054.969725151346;6059.424354686298;6064.75740970365;6069.241344831896;5986.042233242661;6017.06735456005;6030;6080;6100.267967843798;4643.349931009594;4643.976780116078;4646.744429056367;4648.604868586141;4663.790129444184;4029.979850100731;4119.876403707894;4142.420061191436;4159.064210552662;4180.1012782251355;5676.063449530864;5717.056685646821;5719.456651618173;5758.193852860602;5796.362400135981;5998.970005149947;6047.365026672224;6060.272767267956;6065.636061836304;6084.026555751058;5510.311211098613;5726.713664316768;5733.827985160452;5743.253377061039;5745.109508026263;5152.458991805881;5164.535191832678;5178.508041736029;5178.792848286026;5216.347956505437;6164.075388691732;6171.13604095418;6172.259328880563;6172.783952561728;6177.104319873683;5770.047942089508;5950.464458198783;5986.281646202484;5989.3711160329285;5991.310999234969;6120.755169793337;6171.907421388593;6181.505479561714;6235.376462353728;6249.0626406039455;5904.291485021825;5960.3443621202505;5960.910586341295;5964.224650795369;6001.2498437695285;5842.883142337215;6097.939020609737;6107.480864126599;6108.35862234459;6122.387761223842;5797.101449275369;5881.5588830837005;5888.4700376966275;5922.1412895130825;5938.317093534299;5861.062230043172;5909.438603332676;5918.526517878473;5920.585559010828;5922.55580831448;5664.0371136906;5804.564657650778;5846.1815345852465;5856.209411729362;5860.2967643883;5825.3883342248455;5869.413058694096;5887.646741195542;5926.733297001731;5974.641521508724;5785.537157027344;5785.8553536161935;5786.797462088806;5794.101914203306;5795.536357091339 04:59:45 INFO - PROCESS | 23762 | |1;ext.html;612.7824622259097;647.5273050673009;663.9933600663995;674.2583158525622;686.8626274745052;34599.4810077849;46572;47650.52349476504;47656.28515572269;47753.761231193836;49455.25817112746;49685.50314496854;49845.750771246094;49953;50027.749861250646;8617.310615150804;9413;9564.56959436827;9569.760755981086;9579.137877591016;8529.872051919227;8849.734507964771;8850.026497085315;11075.833862492029;11338.943305283503;11450.71373215672;11452.141089418285;11470.139739519569;11481.425928703531;11544.249623774469;11088.501017454233;11174.944125279402;11219.66341009771;11224.270422422516;11231.494582743755;11210.943945280222;11251.718707032307;11270.211085224038;11270.661880143527;11281.943590281997;47490.525094748955;47714.522854771356;47766.761166193945;47816.52183478155;47885;3842.1355195081164;3846.572630536996;4051.3112770828734;4068.3514991815496;4611.792469338888;37099.25801483982;37129.44305835415;37421.62578374236;37506.24987500262;38999.610003899885;4527.7283362998;4554.316852472122;4726.527347265281;4739.454962679301;4781.593564546983;265346.9999999981;265589;265720;266613;267167.6641616799;38759;67453.66273168602;67805.32194678088;67833;67939.3206067933;39329.41005884914;41710.791446042575;41737.79131104386;41749.582504175174;41783.58216417797;17692.823071769373;17815.554611134827;17832.643347133246;17845.196966136555;17852.375166869097;11686.941565292289;14928.850711492963;15095.169765662993;15127.47053853132;15141.394344226108;9055.909440905638;9265.861012084824;9318.767030824283;9461.243100552078;9550;3546.1666508370513;3567.6075631680496;3579.1767893384667;3598.982005089958;3613.7651052682018;1012.4229189361993;1097.928634638746;1119.0823524709822;1152.1128730877178;1223.287322974785;34409.139771505914;34715.826420867736;34832.30335393328;34971.4754278686;34985.65014349824;1114.543037354697;1168;1192.9105317101078;1232.3151403662202;1258.5846670598864;18542;18710.81289187118;18744;18810.435686929413;19075.332363367215;581599;587505;588395;588943;590714;68095;68650.31349686439;68760;68967;69159.30840691629;1357.0636260979893;1409.9154050756988;1452.2158034661165;1485.2893469418354;1485.970280594382;33722;37867.24265514619;38020.23959520849;38148.80925595354;38242.6175738239;63856.68071659612;65842;65892.34107658862;70213;70314.29685703282;381288;383964;384816;385160;386406;30959.535606966365;31612.525812112377;31769;33790.66209337974;46158.07683846371;54847.72576137094;55602.721986389806;56541.43458565362;56619.71690141523;56839;45641.543584563726;46640.30039549477;47522.15065918984;47546.762266188445;47969.520304796504;45741.542584573726;45768.313475298564;46296;47634;47802.760986194844;45407.54592454166;45430.31854522251;45588.31617525806;45964.54035459603;46083.30875036944;39872.80063599663;41435;41961.79019104885;42562.14875702531;43250.35124473072;29342.559861601665;30315.783190067;31147.688523114477;31525.842370788;31754.364912701156 04:59:45 INFO - PROCESS | 23762 | |2;jquery.html;393.7106226923209;416.6686395295431;432.5998451432424;437.65425314001936;441.37324998502146;16345.264463099174;20376.490587735276;20668.793312066875;20684.379468615945;21843.781562184413;20536.691949620767;20566.794332056714;20571.691424628607;20581.176752929863;20773.16907323709;5692.629979051369;5933.970330148354;6024.427679370475;6047.3650266722025;6131.662758548278;6085.878282434349;6109.14471973924;6111.847203819918;6122.724477398528;6203.627782333075;6621.370969757865;6648.900266495983;6683.097781799468;6692.230393504737;6699.765508207213;6481.74073037078;6601.075849381091;6643.103181070542;6684.732610695566;6692.297308782557;5925.614835035709;5926.555508336892;5928.614640048383;5953.226080609493;5954.851128721773;14694.118352898753;14782.48261310849;14942.327595258239;15140.394384224728;15390.307436165287;2325.093213646679;2447.6206188040883;2504.461540768725;2530.1903390914913;2563.205406324027;11349.943250283779;11354.950868481454;11391.259568128127;11465.713357166054;14776.039557428732;1997.6287499128316;2071.958560828775;2074.6265672179;2085.509905172283;2127.0215700777662;66141;66984;69052.96420553696;82437.17562824415;84685;11479.36863472517;11544.59593914209;11700.765984680258;11866.288022718665;11895.524179032742;16613.169341532874;16628.83371166297;16630.251638676287;16634.085125317983;16640.16799160037;8756.78108047304;8853.64585416589;8887.377883548219;8988.32587555936;9086.772830679285;8705.695300664447;8738.781530461789;8765;8953.328500362499;8955.238804701674;4998.225275082368;5075.847724568269;5118.078745825787;5236.502532259429;5255.39562950258;2115.1645100185647;2158.892055397224;2161.221960094365;2161.567686462714;2170.739511258659;965.9661911833052;1025.646152077532;1079.519613771864;1092.5083712329324;1110.1285490889613;20694.166385855104;21182.894085529475;21227;21277.78722212789;21618.567628647652;1006.335818359879;1012.9341592796445;1024.0936770957755;1031.118393773328;1083.8970297821695;10845.069016797499;10903.727406814734;11810.232334898204;11857.407129643483;12109.576164834189;547960;556616;566581;581259;587014;98870;101655;102376.48811756042;102985.48507257566;103445.48277258566;1053.8155822731144;1057.2018126314738;1058.4760543531002;1066.3098988103943;1072.1904961753826;18617.81382186215;18833.623327533645;19098.904505477385;19499.317523886595;19521.902390487958;32940.67059329472;45869.85325366892;47233.527664724286;47318.76340618412;48564;64854.35145648483;65342;65485.34514654792;65978.67010664917;66641.66679166573;9062.592183351633;9114.225290850087;9149.817003660022;9299.488528131018;9512.95243523778;10191.94904025475;12710;13054;13826.239556824472;14408;10835.729106772078;11361.37512436824;11513.53945842157;11524.250923689933;11558.94220528892;12155.331456769962;12173.330466824409;12377.62867113988;12431.502739890304;12580.30808305552;10886.40124793144;11095.278806877526;11134.498947547218;11248.775024499628;11410.201285909907;8045.678172873254;8298.70954516589;9032.819343613222;9386.953065234904;9522.761930951505;8781.91218087811;8843.602037908438;8847.955760221157;9068.818623627623;9194.264458843143 04:59:45 INFO - PROCESS | 23762 | |3;mootools.html;1433.8996270261086;1520.764281536362;1587.7856489373933;1764.964700705986;1901.2109974360633;8516.531590762506;9568.904310956897;9784.510774461267;9808.705738827837;10201.642942497014;9950.353227040237;10020.24848136391;10049.196064314856;10055.396676199414;10057.899421005788;2532.8227024108273;2556.6037264224074;2559.0403598650505;2561.167620523328;2562.128876182098;2485.1923124984364;2580.561304578226;2586.5473542130144;2588.3787890906237;2590.702069262031;2561.2572354017407;2564.7307032761573;2595.4679290745353;2608.4652646207483;2609.125942809161;2483.7640424159677;2518.4837108392826;2518.9874050629815;2527.45659683169;2529.140092368594;2429.380507970474;2442.0842184180933;2446.926592202237;2487.4776296977657;2535.264773215765;9549.522523873848;9794.9510252449;10004.899951000469;10013.098821106063;10033.046860548237;5951.58338916276;6108.083787431877;6120.418560236815;6121.755129794848;6122.05108208224;3594.7663401878795;3644.671979521856;3649.50731651229;3673.981630091859;3773.8490460381554;1229.2133034857698;1233.173773571709;1237.9442925068392;1255.2844878419305;1262.9684257893534;14331.211783351911;14342.067765595202;14345.354459049368;14364.353604087737;14374.20941848198;4951.108800415887;5154.587632989352;5254.133068043807;5259.921101183485;5261.053010458078;11058.11535077192;11078.446077696244;11103.611373601885;11109.44452777358;11145.888541114648;5966.343702192756;5966.76132954677;5971.193888824953;5974.85062873423;5978.282606087298;4413.009666686352;4673.556012178838;4818.807247710122;5416.783328666888;5553.861153471114;4265.210935976854;4266.8293268269545;4338.132373525307;4365.039691267916;4386.517483076859;1254.9811752823532;1255.5480027190204;1261.8990480761518;1265.9240445573296;1272.465564462928;900.0238416911711;987.8370068938689;992.9404235745878;1043.0612448795991;1049.9842502362471;6382.36176382358;6388.39310265524;6418.711157997901;6525.4779617630475;6582.17722784652;503.66506273327826;506.7922151917696;512.9102407078747;514.0336168004203;516.5351183934489;4435.046465010038;4440.444944381952;4507.549245075532;4615.746133962664;4701.506341834127;12743.299118548568;12758.489660413665;12881.806772898413;12892.613221603367;12906.35468226585;11992.700182495508;12543.937280313723;12780.169288996185;12810.039247056322;12824.679383015313;9036.141566551298;9183.862242066241;9234.353595248269;9241.214496767716;9245.491497967541;6437.710303036468;6446.161998940295;6450.193725784277;6538.248101468202;6656.83357916056;10997.945010274898;11719.824202636797;11748.823767643322;11837.526498939946;11892.286462812259;10453.425061621683;11363.31820090764;11549;11695.649130526097;11831.526738930346;8279.875801863098;8665.523396213268;8739.344549158966;8743.562821858754;8770.38607297483;9661.372010819274;9770.267229957924;9793.951030244803;9802.852957205505;9808.215342772419;8691.521966291915;8814.5592720365;8826.117388261122;8905.269150673552;9056.683016094405;8553.360390658725;8630.136986301319;8689.739307820775;8746.606402711766;8830.867536987078;7827.178146294557;7863.882041769263;7904.13054563986;7917.2874441300555;7949.205079492004;5575.832725018256;5593.804216852391;5686.317641883001;5777.277840269966;5821.380378829628;5575.749091290821;5736.598438109292;5765.827025189251;5782.913256301074;5829.970850145722 04:59:45 INFO - PROCESS | 23762 | |4;prototype.html;348.2894894415393;349.94750787381895;354.24192228630733;355.2982858853766;356.34966186709255;5606.271184745982;5683.5169010634045;5690.174924635925;5711.171880077386;5718.456746609076;5527.309086364205;5547.944520554794;5624.15637654351;5636.0982242841155;5652.745626446809;3907.960920390788;4139.99850108671;4364.345348197764;4400.339949007658;4411.360352748866;4374.387585737999;4386.8903277418;4397.582229688191;4402.691811573191;4411.955880441203;4391.560843915615;4399.736015839044;4406.515283318832;4413.484313941055;4424.601785839274;4209.200251952119;4302.612764851178;4363.694541382105;4382.255016647177;4402.669799765031;3922.882313530599;3925.1953349563273;3943.7042221833476;3948.7038472114705;3967.6230758077936;5430.266913966603;5452.536534394581;5482.738970036914;5488.835334939959;5502.202180683818;4761.666683332168;4994.60043196547;5015.824446144404;5028.1954887218235;5084.13569693153;5794.710264486801;5820.738066786962;5825.417458254183;5841.88316233673;5843.328017278025;4264.424302719124;4339.435873336478;4361.672874534423;4376.715513491613;4382.9342559861625;5728.713564321767;5875.177475153482;6058.848528786772;6067.512319948844;6091.969540152271;4379.562043795659;4394.384786129945;4409.7795110245;4411.117776444724;4415.183191109655;4471.128130014616;4486.461624605068;4489.842855500042;4492.05666809967;4494.977525112354;4835.540623640749;4987.202047672355;5050.671706339076;5082.33929589151;5099.847004589868;5777.2200752897825;5799.449052340021;5806.04200306953;5842.444967728059;5881.264841894763;1516.097921736587;1542.9122468659793;1543.6989786991428;1544.5211984284908;1550.7751376050635;973.9464329461806;1014.3660212367271;1015.1574193419444;1020.336781092296;1042.0673497219946;4972.428170760409;5013.949860501421;5058.291839142523;5064.41759197697;5074.949250507448;5250.403473620582;5259.868503287372;5268.841934741964;5282.181261904411;5313.335833020872;4813.229883218668;5035.77339019745;5067.1758167059825;5068.594512438996;5073.644844860898;2811.0301945828655;2816.9859150704115;2823.8305701657555;2826.307554649124;2828.886844526237;5432.772563249128;5465.808696695596;5470.781168753285;5493.533049690741;5494.675721188623;4981.6014718823135;5215.608829337739;5332.733363331818;5340.626156169109;5371.892562148735;5357.892842143136;5369.275147855068;5394.786173111019;5396.487333703292;5404.972975135099;5227.790888364575;5249.265102885675;5282.709450980233;5313.362396512289;5339.0389729849;5438.6947132688665;5836.066229403275;5864.179014937827;5874.559408044328;5898.144769008555;5908.577483231854;6018.699065046818;6130.846728831815;6188.907166392418;6207;4326.437563116775;4353.259945809284;4387.539308372575;4393.275109607007;4399.47206335229;5750.568707347049;6042.643369086148;6049.758009679564;6102.481289090477;6108.664023478751;5809.82570522885;5886.381929897299;5895.233619629421;5895.9115613266695;5902.822915312548;5875.441833025856;5898.970505147447;5911.527077833849;5918.40815918422;5928.822135335947;5700.971495142498;5821.359650438364;5843.7370318336625;5855.795047173328;5869.383714710065;5913.29040515141;5923;5925.05199168131;5960.612560183574;5961.791337303173;5664.291963504562;5732.111522713986;5747.48272655463;5787.421257874179;5810.6513609183585 04:59:45 INFO - PROCESS | 23762 | |5;yui.html;782.1279273609923;796.4902462424049;801.0467543623088;801.5551368990208;940.7318914109478;13966.16203027819;14802.111873287611;14823.999999999987;14913.403463861432;14940.626484337898;14072;14208.147511149313;14275.785863212033;14278.857211427883;14287.642808929782;744.7058411927286;845.2603971524916;849.3672214200413;869.2003356911625;876.9868451973225;893.3880291999968;895.5656506594297;895.6775560798108;895.8118795053047;898.0704970355683;931.7065124485799;935.504182783126;936.6768464879606;938.0947385772728;938.53542496464;779.7777633374461;798.9840203195932;894.4186278918689;922.4004397141845;933.2300851797261;679.7450955891541;684.7979845945458;685.3215316836346;685.7017197519045;686.5365878032309;12867.742645147044;12944.676383090498;12977.935110324483;12991.740165196738;13052.28212448315;14305.499307524187;14440.927795361062;14497.347619357155;14525.92737036308;14536.92731536346;13745.587632371045;13837.515686951008;13900.235487048209;14073.71852562943;14166.150030998173;4503.459584849839;4550.135474259913;4596.310553416981;4620.237660785966;4686.179918514281;82781.58609206915;83958.58020709918;84783.5760821198;85058.57470712668;86769.13230867736;10905.34567925927;10977.945110274397;11038.848464735089;11121.443927803577;11131.165162612837;19598.314059007866;19602.11790469432;19607.705884411746;19619.313324033876;19621.901890490455;8145.3483721302155;8177.836443271101;8296.460730052528;8493.872591911126;8837.955810220908;4628.421447319085;4631.652626053059;4633.49031606523;4639;5056.721880296545;3456.671616196508;3562.5190599268767;3566.4828599852876;3584.7849129052343;3589.94615080774;649.4349915573481;671.9563228390236;680.7685386968454;681.9761308354184;703.8134894253027;682.3585829320423;692.3284414118298;710.3393843725282;725.5320318394712;726.4478995963107;8823.251064677634;9067.637294508146;9103.726888193365;9142.588583513758;9172.495512746864;630.6941133550224;676.1210426445601;677.4072686399401;689.0491122251261;711.3562226185311;4298.957010429918;4403.3174857897075;4418.690691651554;4446.466424029137;4503.909921801545;103052.48473757734;104742.47628761808;108097.45951270193;131161;134227.32886335507;61457;73772.63113684398;99517.50241248892;99712;100252.49873750584;86078;91577;94407.33555986662;94718.5264073689;96643;9188.632454702005;9514.143727064597;9582.760430989003;9692.660756873758;9740.707778766648;54315;54628;55214.72392638011;55886.441135589754;56141;47742;47832.282515762985;62807.6859615699;76903.61548192223;77163.22836771785;11929.701757456132;12508.186967847061;12529.248245105322;12647.367631618567;13010.414531345923;14390.56828295111;14426.206558639376;14603.780943286072;14744.483943061941;15359.539213823604;11970.990910951225;12290.20113692607;12413.937930310653;12648.43082061328;13172.47310107585;12204.450799714212;12387.62837114888;12428.689282768004;12675.429605667583;12958.546450874173;11652.184347095961;12087.274763514217;12365.381730913597;12413.503459861504;12847.743045139232;10925.617603383844;10976.450002726408;11485.196036277715;11580.768384632429;11711.824322635337;10460.44606642408;11007.559697612005;11048.392338421463;11053.226274161054;11076.778464430827 04:59:45 INFO - PROCESS | 23762 | __end_tp_report 04:59:45 INFO - PROCESS | 23762 | __start_cc_report 04:59:45 INFO - PROCESS | 23762 | _x_x_mozilla_cycle_collect,1061 04:59:45 INFO - PROCESS | 23762 | __end_cc_report 04:59:45 INFO - PROCESS | 23762 | __startTimestamp1471262385426__endTimestamp 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | ------- Summary: start ------- 04:59:45 INFO - PROCESS | 23762 | Number of tests: 6 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#0] dojo.html Cycles:165 Average:5605.01 Median:5796.36 stddev:629.68 (10.9%) stddev-sans-first:603.74 04:59:45 INFO - PROCESS | 23762 | Values: 3243.5 3262.3 3264.2 3278.8 3306.9 5534.5 5635.2 5637.1 5638.0 5641.9 5613.4 5682.2 5704.6 5729.1 5755.1 4962.6 5073.3 5168.5 5200.2 5255.1 5152.0 5191.2 5192.2 5196.4 5202.2 5090.9 5130.9 5154.0 5161.4 5173.9 5213.4 5222.7 5240.7 5255.4 5272.9 4971.9 4979.4 4984.9 5150.1 5153.5 5491.9 5508.1 5519.9 5530.1 5535.6 5975.2 6025.0 6025.6 6027.3 6035.4 6005.1 6020.5 6068.9 6070.6 6137.0 5691.0 5727.7 5739.8 5748.5 5749.6 5941.8 6244.9 6245.8 6249.3 6253.4 5872.7 5917.0 5917.6 5959.4 6022.8 6082.1 6089.1 6109.7 6111.9 6114.3 5775.8 6055.0 6059.4 6064.8 6069.2 5986.0 6017.1 6030.0 6080.0 6100.3 4643.3 4644.0 4646.7 4648.6 4663.8 4030.0 4119.9 4142.4 4159.1 4180.1 5676.1 5717.1 5719.5 5758.2 5796.4 5999.0 6047.4 6060.3 6065.6 6084.0 5510.3 5726.7 5733.8 5743.3 5745.1 5152.5 5164.5 5178.5 5178.8 5216.3 6164.1 6171.1 6172.3 6172.8 6177.1 5770.0 5950.5 5986.3 5989.4 5991.3 6120.8 6171.9 6181.5 6235.4 6249.1 5904.3 5960.3 5960.9 5964.2 6001.2 5842.9 6097.9 6107.5 6108.4 6122.4 5797.1 5881.6 5888.5 5922.1 5938.3 5861.1 5909.4 5918.5 5920.6 5922.6 5664.0 5804.6 5846.2 5856.2 5860.3 5825.4 5869.4 5887.6 5926.7 5974.6 5785.5 5785.9 5786.8 5794.1 5795.5 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#1] ext.html Cycles:180 Average:60056.86 Median:39601.11 stddev:115145.38 (290.8%) stddev-sans-first:115381.89 04:59:45 INFO - PROCESS | 23762 | Values: 612.8 647.5 664.0 674.3 686.9 34599.5 46572.0 47650.5 47656.3 47753.8 49455.3 49685.5 49845.8 49953.0 50027.7 8617.3 9413.0 9564.6 9569.8 9579.1 8529.9 8849.7 8850.0 11075.8 11338.9 11450.7 11452.1 11470.1 11481.4 11544.2 11088.5 11174.9 11219.7 11224.3 11231.5 11210.9 11251.7 11270.2 11270.7 11281.9 47490.5 47714.5 47766.8 47816.5 47885.0 3842.1 3846.6 4051.3 4068.4 4611.8 37099.3 37129.4 37421.6 37506.2 38999.6 4527.7 4554.3 4726.5 4739.5 4781.6 265347.0 265589.0 265720.0 266613.0 267167.7 38759.0 67453.7 67805.3 67833.0 67939.3 39329.4 41710.8 41737.8 41749.6 41783.6 17692.8 17815.6 17832.6 17845.2 17852.4 11686.9 14928.9 15095.2 15127.5 15141.4 9055.9 9265.9 9318.8 9461.2 9550.0 3546.2 3567.6 3579.2 3599.0 3613.8 1012.4 1097.9 1119.1 1152.1 1223.3 34409.1 34715.8 34832.3 34971.5 34985.7 1114.5 1168.0 1192.9 1232.3 1258.6 18542.0 18710.8 18744.0 18810.4 19075.3 581599.0 587505.0 588395.0 588943.0 590714.0 68095.0 68650.3 68760.0 68967.0 69159.3 1357.1 1409.9 1452.2 1485.3 1486.0 33722.0 37867.2 38020.2 38148.8 38242.6 63856.7 65842.0 65892.3 70213.0 70314.3 381288.0 383964.0 384816.0 385160.0 386406.0 30959.5 31612.5 31769.0 33790.7 46158.1 54847.7 55602.7 56541.4 56619.7 56839.0 45641.5 46640.3 47522.2 47546.8 47969.5 45741.5 45768.3 46296.0 47634.0 47802.8 45407.5 45430.3 45588.3 45964.5 46083.3 39872.8 41435.0 41961.8 42562.1 43250.4 29342.6 30315.8 31147.7 31525.8 31754.4 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#2] jquery.html Cycles:180 Average:31684.61 Median:11894.65 stddev:93438.12 (785.5%) stddev-sans-first:93670.70 04:59:45 INFO - PROCESS | 23762 | Values: 393.7 416.7 432.6 437.7 441.4 16345.3 20376.5 20668.8 20684.4 21843.8 20536.7 20566.8 20571.7 20581.2 20773.2 5692.6 5934.0 6024.4 6047.4 6131.7 6085.9 6109.1 6111.8 6122.7 6203.6 6621.4 6648.9 6683.1 6692.2 6699.8 6481.7 6601.1 6643.1 6684.7 6692.3 5925.6 5926.6 5928.6 5953.2 5954.9 14694.1 14782.5 14942.3 15140.4 15390.3 2325.1 2447.6 2504.5 2530.2 2563.2 11349.9 11355.0 11391.3 11465.7 14776.0 1997.6 2072.0 2074.6 2085.5 2127.0 66141.0 66984.0 69053.0 82437.2 84685.0 11479.4 11544.6 11700.8 11866.3 11895.5 16613.2 16628.8 16630.3 16634.1 16640.2 8756.8 8853.6 8887.4 8988.3 9086.8 8705.7 8738.8 8765.0 8953.3 8955.2 4998.2 5075.8 5118.1 5236.5 5255.4 2115.2 2158.9 2161.2 2161.6 2170.7 966.0 1025.6 1079.5 1092.5 1110.1 20694.2 21182.9 21227.0 21277.8 21618.6 1006.3 1012.9 1024.1 1031.1 1083.9 10845.1 10903.7 11810.2 11857.4 12109.6 547960.0 556616.0 566581.0 581259.0 587014.0 98870.0 101655.0 102376.5 102985.5 103445.5 1053.8 1057.2 1058.5 1066.3 1072.2 18617.8 18833.6 19098.9 19499.3 19521.9 32940.7 45869.9 47233.5 47318.8 48564.0 64854.4 65342.0 65485.3 65978.7 66641.7 9062.6 9114.2 9149.8 9299.5 9513.0 10191.9 12710.0 13054.0 13826.2 14408.0 10835.7 11361.4 11513.5 11524.3 11558.9 12155.3 12173.3 12377.6 12431.5 12580.3 10886.4 11095.3 11134.5 11248.8 11410.2 8045.7 8298.7 9032.8 9387.0 9522.8 8781.9 8843.6 8848.0 9068.8 9194.3 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#3] mootools.html Cycles:180 Average:6497.02 Median:4424.03 stddev:3830.23 (86.6%) stddev-sans-first:3822.07 04:59:45 INFO - PROCESS | 23762 | Values: 1433.9 1520.8 1587.8 1765.0 1901.2 8516.5 9568.9 9784.5 9808.7 10201.6 9950.4 10020.2 10049.2 10055.4 10057.9 2532.8 2556.6 2559.0 2561.2 2562.1 2485.2 2580.6 2586.5 2588.4 2590.7 2561.3 2564.7 2595.5 2608.5 2609.1 2483.8 2518.5 2519.0 2527.5 2529.1 2429.4 2442.1 2446.9 2487.5 2535.3 9549.5 9795.0 10004.9 10013.1 10033.0 5951.6 6108.1 6120.4 6121.8 6122.1 3594.8 3644.7 3649.5 3674.0 3773.8 1229.2 1233.2 1237.9 1255.3 1263.0 14331.2 14342.1 14345.4 14364.4 14374.2 4951.1 5154.6 5254.1 5259.9 5261.1 11058.1 11078.4 11103.6 11109.4 11145.9 5966.3 5966.8 5971.2 5974.9 5978.3 4413.0 4673.6 4818.8 5416.8 5553.9 4265.2 4266.8 4338.1 4365.0 4386.5 1255.0 1255.5 1261.9 1265.9 1272.5 900.0 987.8 992.9 1043.1 1050.0 6382.4 6388.4 6418.7 6525.5 6582.2 503.7 506.8 512.9 514.0 516.5 4435.0 4440.4 4507.5 4615.7 4701.5 12743.3 12758.5 12881.8 12892.6 12906.4 11992.7 12543.9 12780.2 12810.0 12824.7 9036.1 9183.9 9234.4 9241.2 9245.5 6437.7 6446.2 6450.2 6538.2 6656.8 10997.9 11719.8 11748.8 11837.5 11892.3 10453.4 11363.3 11549.0 11695.6 11831.5 8279.9 8665.5 8739.3 8743.6 8770.4 9661.4 9770.3 9794.0 9802.9 9808.2 8691.5 8814.6 8826.1 8905.3 9056.7 8553.4 8630.1 8689.7 8746.6 8830.9 7827.2 7863.9 7904.1 7917.3 7949.2 5575.8 5593.8 5686.3 5777.3 5821.4 5575.7 5736.6 5765.8 5782.9 5830.0 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#4] prototype.html Cycles:180 Average:4802.38 Median:5275.51 stddev:1375.63 (26.1%) stddev-sans-first:1338.25 04:59:45 INFO - PROCESS | 23762 | Values: 348.3 349.9 354.2 355.3 356.3 5606.3 5683.5 5690.2 5711.2 5718.5 5527.3 5547.9 5624.2 5636.1 5652.7 3908.0 4140.0 4364.3 4400.3 4411.4 4374.4 4386.9 4397.6 4402.7 4412.0 4391.6 4399.7 4406.5 4413.5 4424.6 4209.2 4302.6 4363.7 4382.3 4402.7 3922.9 3925.2 3943.7 3948.7 3967.6 5430.3 5452.5 5482.7 5488.8 5502.2 4761.7 4994.6 5015.8 5028.2 5084.1 5794.7 5820.7 5825.4 5841.9 5843.3 4264.4 4339.4 4361.7 4376.7 4382.9 5728.7 5875.2 6058.8 6067.5 6092.0 4379.6 4394.4 4409.8 4411.1 4415.2 4471.1 4486.5 4489.8 4492.1 4495.0 4835.5 4987.2 5050.7 5082.3 5099.8 5777.2 5799.4 5806.0 5842.4 5881.3 1516.1 1542.9 1543.7 1544.5 1550.8 973.9 1014.4 1015.2 1020.3 1042.1 4972.4 5013.9 5058.3 5064.4 5074.9 5250.4 5259.9 5268.8 5282.2 5313.3 4813.2 5035.8 5067.2 5068.6 5073.6 2811.0 2817.0 2823.8 2826.3 2828.9 5432.8 5465.8 5470.8 5493.5 5494.7 4981.6 5215.6 5332.7 5340.6 5371.9 5357.9 5369.3 5394.8 5396.5 5405.0 5227.8 5249.3 5282.7 5313.4 5339.0 5438.7 5836.1 5864.2 5874.6 5898.1 5908.6 6018.7 6130.8 6188.9 6207.0 4326.4 4353.3 4387.5 4393.3 4399.5 5750.6 6042.6 6049.8 6102.5 6108.7 5809.8 5886.4 5895.2 5895.9 5902.8 5875.4 5899.0 5911.5 5918.4 5928.8 5701.0 5821.4 5843.7 5855.8 5869.4 5913.3 5923.0 5925.1 5960.6 5961.8 5664.3 5732.1 5747.5 5787.4 5810.7 04:59:45 INFO - PROCESS | 23762 | 04:59:45 INFO - PROCESS | 23762 | [#5] yui.html Cycles:180 Average:20482.98 Median:4630.04 stddev:30003.73 (648.0%) stddev-sans-first:30051.43 04:59:45 INFO - PROCESS | 23762 | Values: 782.1 796.5 801.0 801.6 940.7 13966.2 14802.1 14824.0 14913.4 14940.6 14072.0 14208.1 14275.8 14278.9 14287.6 744.7 845.3 849.4 869.2 877.0 893.4 895.6 895.7 895.8 898.1 931.7 935.5 936.7 938.1 938.5 779.8 799.0 894.4 922.4 933.2 679.7 684.8 685.3 685.7 686.5 12867.7 12944.7 12977.9 12991.7 13052.3 14305.5 14440.9 14497.3 14525.9 14536.9 13745.6 13837.5 13900.2 14073.7 14166.2 4503.5 4550.1 4596.3 4620.2 4686.2 82781.6 83958.6 84783.6 85058.6 86769.1 10905.3 10977.9 11038.8 11121.4 11131.2 19598.3 19602.1 19607.7 19619.3 19621.9 8145.3 8177.8 8296.5 8493.9 8838.0 4628.4 4631.7 4633.5 4639.0 5056.7 3456.7 3562.5 3566.5 3584.8 3589.9 649.4 672.0 680.8 682.0 703.8 682.4 692.3 710.3 725.5 726.4 8823.3 9067.6 9103.7 9142.6 9172.5 630.7 676.1 677.4 689.0 711.4 4299.0 4403.3 4418.7 4446.5 4503.9 103052.5 104742.5 108097.5 131161.0 134227.3 61457.0 73772.6 99517.5 99712.0 100252.5 86078.0 91577.0 94407.3 94718.5 96643.0 9188.6 9514.1 9582.8 9692.7 9740.7 54315.0 54628.0 55214.7 55886.4 56141.0 47742.0 47832.3 62807.7 76903.6 77163.2 11929.7 12508.2 12529.2 12647.4 13010.4 14390.6 14426.2 14603.8 14744.5 15359.5 11971.0 12290.2 12413.9 12648.4 13172.5 12204.5 12387.6 12428.7 12675.4 12958.5 11652.2 12087.3 12365.4 12413.5 12847.7 10925.6 10976.5 11485.2 11580.8 11711.8 10460.4 11007.6 11048.4 11053.2 11076.8 04:59:45 INFO - PROCESS | 23762 | -------- Summary: end -------- 04:59:45 INFO - PROCESS | 23762 | 04:59:46 INFO - TEST-INFO | 23762: exit 0 04:59:46 INFO - TEST-OK | dromaeo_css | took 1113584ms 04:59:46 INFO - TEST-START | kraken 04:59:46 INFO - operating with platform_type : linux_ 04:59:46 INFO - Initialising browser for kraken test... 04:59:46 INFO - TEST-INFO | started process 23961 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_mmAwL/profile http://localhost:41760/getInfo.html) 04:59:50 INFO - PROCESS | 23961 | __metrics Screen width/height:1600/1200 04:59:50 INFO - PROCESS | 23961 | colorDepth:24 04:59:50 INFO - PROCESS | 23961 | Browser inner width/height: 1024/697 04:59:50 INFO - PROCESS | 23961 | __metrics 04:59:51 INFO - PROCESS | 23961 | 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" 04:59:51 INFO - PROCESS | 23961 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:59:51 INFO - TEST-INFO | 23961: exit 0 04:59:51 INFO - Browser initialized. 04:59:51 INFO - Running cycle 1/1 for kraken test... 04:59:51 INFO - TEST-INFO | started process 24049 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_mmAwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:00:03 INFO - PROCESS | 24049 | RSS: Main: 145702912 05:00:03 INFO - PROCESS | 24049 | 05:00:06 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41760/tests/kraken/driver.html?testName=audio-beat-detection) 05:00:06 INFO - PROCESS | 24049 | RSS: Main: 191442944 05:00:06 INFO - PROCESS | 24049 | 05:00:09 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41760/tests/kraken/driver.html?testName=audio-dft) 05:00:10 INFO - PROCESS | 24049 | RSS: Main: 182468608 05:00:10 INFO - PROCESS | 24049 | 05:00:12 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41760/tests/kraken/driver.html?testName=audio-fft) 05:00:13 INFO - PROCESS | 24049 | RSS: Main: 271511552 05:00:13 INFO - PROCESS | 24049 | 05:00:15 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41760/tests/kraken/driver.html?testName=audio-oscillator) 05:00:16 INFO - PROCESS | 24049 | RSS: Main: 176205824 05:00:16 INFO - PROCESS | 24049 | 05:00:18 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41760/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:00:18 INFO - PROCESS | 24049 | RSS: Main: 177496064 05:00:18 INFO - PROCESS | 24049 | 05:00:21 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41760/tests/kraken/driver.html?testName=imaging-darkroom) 05:00:22 INFO - PROCESS | 24049 | RSS: Main: 212877312 05:00:22 INFO - PROCESS | 24049 | 05:00:25 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41760/tests/kraken/driver.html?testName=imaging-desaturate) 05:00:26 INFO - PROCESS | 24049 | RSS: Main: 212062208 05:00:26 INFO - PROCESS | 24049 | 05:00:29 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41760/tests/kraken/driver.html?testName=json-parse-financial) 05:00:29 INFO - PROCESS | 24049 | RSS: Main: 211443712 05:00:29 INFO - PROCESS | 24049 | 05:00:31 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41760/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:00:31 INFO - PROCESS | 24049 | RSS: Main: 176222208 05:00:31 INFO - PROCESS | 24049 | 05:00:33 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:00:34 INFO - PROCESS | 24049 | RSS: Main: 181702656 05:00:34 INFO - PROCESS | 24049 | 05:00:36 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:00:36 INFO - PROCESS | 24049 | RSS: Main: 186773504 05:00:36 INFO - PROCESS | 24049 | 05:00:39 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:00:39 INFO - PROCESS | 24049 | RSS: Main: 180879360 05:00:39 INFO - PROCESS | 24049 | 05:00:42 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:00:43 INFO - PROCESS | 24049 | RSS: Main: 184573952 05:00:43 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | Cycle 1(1): loaded http://localhost:41760/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41760/tests/kraken/driver.html?testName=ai-astar) 05:00:44 INFO - PROCESS | 24049 | RSS: Main: 235380736 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | __start_tp_report 05:00:44 INFO - PROCESS | 24049 | _x_x_mozilla_page_load 05:00:44 INFO - PROCESS | 24049 | _x_x_mozilla_page_load_details 05:00:44 INFO - PROCESS | 24049 | |i|pagename|runs| 05:00:44 INFO - PROCESS | 24049 | |0;ai-astar;103;113;111;111;111;109;110;110;112;110 05:00:44 INFO - PROCESS | 24049 | |1;audio-beat-detection;140;115;114;170;122;118;147;126;152;119 05:00:44 INFO - PROCESS | 24049 | |2;audio-dft;161;160;160;162;160;161;161;161;162;159 05:00:44 INFO - PROCESS | 24049 | |3;audio-fft;73;75;74;109;76;75;74;75;104;75 05:00:44 INFO - PROCESS | 24049 | |4;audio-oscillator;84;84;132;83;84;84;111;83;84;126 05:00:44 INFO - PROCESS | 24049 | |5;imaging-gaussian-blur;117;116;117;116;116;117;117;117;117;117 05:00:44 INFO - PROCESS | 24049 | |6;imaging-darkroom;145;146;145;145;145;145;145;146;145;147 05:00:44 INFO - PROCESS | 24049 | |7;imaging-desaturate;94;94;95;94;94;95;95;95;94;94 05:00:44 INFO - PROCESS | 24049 | |8;json-parse-financial;49;48;49;49;49;49;49;50;49;50 05:00:44 INFO - PROCESS | 24049 | |9;json-stringify-tinderbox;59;60;60;59;59;60;59;58;59;59 05:00:44 INFO - PROCESS | 24049 | |10;stanford-crypto-aes;78;72;72;83;72;71;72;73;73;73 05:00:44 INFO - PROCESS | 24049 | |11;stanford-crypto-ccm;117;123;118;115;132;114;125;117;154;117 05:00:44 INFO - PROCESS | 24049 | |12;stanford-crypto-pbkdf2;154;153;152;149;151;151;151;149;149;152 05:00:44 INFO - PROCESS | 24049 | |13;stanford-crypto-sha256-iterative;63;62;61;63;61;64;62;68;59;59 05:00:44 INFO - PROCESS | 24049 | __end_tp_report 05:00:44 INFO - PROCESS | 24049 | __start_cc_report 05:00:44 INFO - PROCESS | 24049 | _x_x_mozilla_cycle_collect,1591 05:00:44 INFO - PROCESS | 24049 | __end_cc_report 05:00:44 INFO - PROCESS | 24049 | __startTimestamp1471262444926__endTimestamp 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | ------- Summary: start ------- 05:00:44 INFO - PROCESS | 24049 | Number of tests: 14 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:2.71 (2.4%) stddev-sans-first:1.20 05:00:44 INFO - PROCESS | 24049 | Values: 103.0 113.0 111.0 111.0 111.0 109.0 110.0 110.0 112.0 110.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:133.00 stddev:18.99 (14.3%) stddev-sans-first:19.94 05:00:44 INFO - PROCESS | 24049 | Values: 140.0 115.0 114.0 170.0 122.0 118.0 147.0 126.0 152.0 119.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 05:00:44 INFO - PROCESS | 24049 | Values: 161.0 160.0 160.0 162.0 160.0 161.0 161.0 161.0 162.0 159.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#3] audio-fft Cycles:10 Average:81.00 Median:75.00 stddev:13.52 (18.0%) stddev-sans-first:14.02 05:00:44 INFO - PROCESS | 24049 | Values: 73.0 75.0 74.0 109.0 76.0 75.0 74.0 75.0 104.0 75.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:84.00 stddev:19.65 (23.4%) stddev-sans-first:20.40 05:00:44 INFO - PROCESS | 24049 | Values: 84.0 84.0 132.0 83.0 84.0 84.0 111.0 83.0 84.0 126.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:00:44 INFO - PROCESS | 24049 | Values: 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 05:00:44 INFO - PROCESS | 24049 | Values: 145.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 145.0 147.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:00:44 INFO - PROCESS | 24049 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 94.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:0.57 (1.2%) stddev-sans-first:0.60 05:00:44 INFO - PROCESS | 24049 | Values: 49.0 48.0 49.0 49.0 49.0 49.0 49.0 50.0 49.0 50.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 05:00:44 INFO - PROCESS | 24049 | Values: 59.0 60.0 60.0 59.0 59.0 60.0 59.0 58.0 59.0 59.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.00 stddev:3.73 (5.1%) stddev-sans-first:3.64 05:00:44 INFO - PROCESS | 24049 | Values: 78.0 72.0 72.0 83.0 72.0 71.0 72.0 73.0 73.0 73.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#11] stanford-crypto-ccm Cycles:10 Average:123.20 Median:120.50 stddev:12.13 (10.1%) stddev-sans-first:12.65 05:00:44 INFO - PROCESS | 24049 | Values: 117.0 123.0 118.0 115.0 132.0 114.0 125.0 117.0 154.0 117.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:151.50 stddev:1.73 (1.1%) stddev-sans-first:1.48 05:00:44 INFO - PROCESS | 24049 | Values: 154.0 153.0 152.0 149.0 151.0 151.0 151.0 149.0 149.0 152.0 05:00:44 INFO - PROCESS | 24049 | 05:00:44 INFO - PROCESS | 24049 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:2.62 (4.2%) stddev-sans-first:2.76 05:00:44 INFO - PROCESS | 24049 | Values: 63.0 62.0 61.0 63.0 61.0 64.0 62.0 68.0 59.0 59.0 05:00:44 INFO - PROCESS | 24049 | -------- Summary: end -------- 05:00:44 INFO - PROCESS | 24049 | 05:00:46 INFO - TEST-INFO | 24049: exit 0 05:00:46 INFO - TEST-OK | kraken | took 59830ms 05:00:46 INFO - SUITE-END | took 1173s 05:00:46 INFO - Completed test suite (00:19:33) 05:00:46 INFO - javascript benchmark 05:00:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3243.4810430331154, 3262.3312220994712, 3264.2329052672626, 3278.8360581970896, 3306.884259050934, 5534.501894829465, 5635.154726790984, 5637.126245431965, 5638.013347664158, 5641.915371269434, 5613.4386561343845, 5682.17608446775, 5704.600677952545, 5729.0833466645345, 5755.136729490579, 4962.5781808546135, 5073.264376665381, 5168.457311982244, 5200.193969934648, 5255.106631872572, 5152.0, 5191.195364718475, 5192.221166824968, 5196.376434827807, 5202.245674377232, 5090.898182036375, 5130.871728206806, 5153.974230128844, 5161.406438259611, 5173.87065323368, 5213.4004589472315, 5222.73886305687, 5240.711760853152, 5255.421903590602, 5272.945410917812, 4971.875703107415, 4979.3526841510375, 4984.87537811554, 5150.098732721759, 5153.458886816905, 5491.917621235684, 5508.14623733322, 5519.917201241985, 5530.087535556629, 5535.612507124503, 5975.163477113208, 6024.969875150597, 6025.578209525335, 6027.276726792769, 6035.396460353973, 6005.069214271794, 6020.548458865559, 6068.878622427526, 6070.6357618543025, 6136.96931515344, 5691.004074286983, 5727.713614319267, 5739.82780516581, 5748.540116790647, 5749.626274292157, 5941.7623295067715, 6244.875102498016, 6245.843853903598, 6249.25008998923, 6253.405926436982, 5872.7357268923, 5917.0, 5917.55618328627, 5959.433853783884, 6022.759089636453, 6082.148499210114, 6089.117078023662, 6109.725062372204, 6111.938880611226, 6114.296855861633, 5775.797847075332, 6054.969725151346, 6059.424354686298, 6064.75740970365, 6069.241344831896, 5986.042233242661, 6017.06735456005, 6030.0, 6080.0, 6100.267967843798, 4643.349931009594, 4643.976780116078, 4646.744429056367, 4648.604868586141, 4663.790129444184, 4029.979850100731, 4119.876403707894, 4142.420061191436, 4159.064210552662, 4180.1012782251355, 5676.063449530864, 5717.056685646821, 5719.456651618173, 5758.193852860602, 5796.362400135981, 5998.970005149947, 6047.365026672224, 6060.272767267956, 6065.636061836304, 6084.026555751058, 5510.311211098613, 5726.713664316768, 5733.827985160452, 5743.253377061039, 5745.109508026263, 5152.458991805881, 5164.535191832678, 5178.508041736029, 5178.792848286026, 5216.347956505437, 6164.075388691732, 6171.13604095418, 6172.259328880563, 6172.783952561728, 6177.104319873683, 5770.047942089508, 5950.464458198783, 5986.281646202484, 5989.3711160329285, 5991.310999234969, 6120.755169793337, 6171.907421388593, 6181.505479561714, 6235.376462353728, 6249.0626406039455, 5904.291485021825, 5960.3443621202505, 5960.910586341295, 5964.224650795369, 6001.2498437695285, 5842.883142337215, 6097.939020609737, 6107.480864126599, 6108.35862234459, 6122.387761223842, 5797.101449275369, 5881.5588830837005, 5888.4700376966275, 5922.1412895130825, 5938.317093534299, 5861.062230043172, 5909.438603332676, 5918.526517878473, 5920.585559010828, 5922.55580831448, 5664.0371136906, 5804.564657650778, 5846.1815345852465, 5856.209411729362, 5860.2967643883, 5825.3883342248455, 5869.413058694096, 5887.646741195542, 5926.733297001731, 5974.641521508724, 5785.537157027344, 5785.8553536161935, 5786.797462088806, 5794.101914203306, 5795.536357091339], "value": 5562.892839233724, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.7824622259097, 647.5273050673009, 663.9933600663995, 674.2583158525622, 686.8626274745052, 34599.4810077849, 46572.0, 47650.52349476504, 47656.28515572269, 47753.761231193836, 49455.25817112746, 49685.50314496854, 49845.750771246094, 49953.0, 50027.749861250646, 8617.310615150804, 9413.0, 9564.56959436827, 9569.760755981086, 9579.137877591016, 8529.872051919227, 8849.734507964771, 8850.026497085315, 11075.833862492029, 11338.943305283503, 11450.71373215672, 11452.141089418285, 11470.139739519569, 11481.425928703531, 11544.249623774469, 11088.501017454233, 11174.944125279402, 11219.66341009771, 11224.270422422516, 11231.494582743755, 11210.943945280222, 11251.718707032307, 11270.211085224038, 11270.661880143527, 11281.943590281997, 47490.525094748955, 47714.522854771356, 47766.761166193945, 47816.52183478155, 47885.0, 3842.1355195081164, 3846.572630536996, 4051.3112770828734, 4068.3514991815496, 4611.792469338888, 37099.25801483982, 37129.44305835415, 37421.62578374236, 37506.24987500262, 38999.610003899885, 4527.7283362998, 4554.316852472122, 4726.527347265281, 4739.454962679301, 4781.593564546983, 265346.9999999981, 265589.0, 265720.0, 266613.0, 267167.6641616799, 38759.0, 67453.66273168602, 67805.32194678088, 67833.0, 67939.3206067933, 39329.41005884914, 41710.791446042575, 41737.79131104386, 41749.582504175174, 41783.58216417797, 17692.823071769373, 17815.554611134827, 17832.643347133246, 17845.196966136555, 17852.375166869097, 11686.941565292289, 14928.850711492963, 15095.169765662993, 15127.47053853132, 15141.394344226108, 9055.909440905638, 9265.861012084824, 9318.767030824283, 9461.243100552078, 9550.0, 3546.1666508370513, 3567.6075631680496, 3579.1767893384667, 3598.982005089958, 3613.7651052682018, 1012.4229189361993, 1097.928634638746, 1119.0823524709822, 1152.1128730877178, 1223.287322974785, 34409.139771505914, 34715.826420867736, 34832.30335393328, 34971.4754278686, 34985.65014349824, 1114.543037354697, 1168.0, 1192.9105317101078, 1232.3151403662202, 1258.5846670598864, 18542.0, 18710.81289187118, 18744.0, 18810.435686929413, 19075.332363367215, 581599.0, 587505.0, 588395.0, 588943.0, 590714.0, 68095.0, 68650.31349686439, 68760.0, 68967.0, 69159.30840691629, 1357.0636260979893, 1409.9154050756988, 1452.2158034661165, 1485.2893469418354, 1485.970280594382, 33722.0, 37867.24265514619, 38020.23959520849, 38148.80925595354, 38242.6175738239, 63856.68071659612, 65842.0, 65892.34107658862, 70213.0, 70314.29685703282, 381288.0, 383964.0, 384816.0, 385160.0, 386406.0, 30959.535606966365, 31612.525812112377, 31769.0, 33790.66209337974, 46158.07683846371, 54847.72576137094, 55602.721986389806, 56541.43458565362, 56619.71690141523, 56839.0, 45641.543584563726, 46640.30039549477, 47522.15065918984, 47546.762266188445, 47969.520304796504, 45741.542584573726, 45768.313475298564, 46296.0, 47634.0, 47802.760986194844, 45407.54592454166, 45430.31854522251, 45588.31617525806, 45964.54035459603, 46083.30875036944, 39872.80063599663, 41435.0, 41961.79019104885, 42562.14875702531, 43250.35124473072, 29342.559861601665, 30315.783190067, 31147.688523114477, 31525.842370788, 31754.364912701156], "value": 21104.842070956995, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.7106226923209, 416.6686395295431, 432.5998451432424, 437.65425314001936, 441.37324998502146, 16345.264463099174, 20376.490587735276, 20668.793312066875, 20684.379468615945, 21843.781562184413, 20536.691949620767, 20566.794332056714, 20571.691424628607, 20581.176752929863, 20773.16907323709, 5692.629979051369, 5933.970330148354, 6024.427679370475, 6047.3650266722025, 6131.662758548278, 6085.878282434349, 6109.14471973924, 6111.847203819918, 6122.724477398528, 6203.627782333075, 6621.370969757865, 6648.900266495983, 6683.097781799468, 6692.230393504737, 6699.765508207213, 6481.74073037078, 6601.075849381091, 6643.103181070542, 6684.732610695566, 6692.297308782557, 5925.614835035709, 5926.555508336892, 5928.614640048383, 5953.226080609493, 5954.851128721773, 14694.118352898753, 14782.48261310849, 14942.327595258239, 15140.394384224728, 15390.307436165287, 2325.093213646679, 2447.6206188040883, 2504.461540768725, 2530.1903390914913, 2563.205406324027, 11349.943250283779, 11354.950868481454, 11391.259568128127, 11465.713357166054, 14776.039557428732, 1997.6287499128316, 2071.958560828775, 2074.6265672179, 2085.509905172283, 2127.0215700777662, 66141.0, 66984.0, 69052.96420553696, 82437.17562824415, 84685.0, 11479.36863472517, 11544.59593914209, 11700.765984680258, 11866.288022718665, 11895.524179032742, 16613.169341532874, 16628.83371166297, 16630.251638676287, 16634.085125317983, 16640.16799160037, 8756.78108047304, 8853.64585416589, 8887.377883548219, 8988.32587555936, 9086.772830679285, 8705.695300664447, 8738.781530461789, 8765.0, 8953.328500362499, 8955.238804701674, 4998.225275082368, 5075.847724568269, 5118.078745825787, 5236.502532259429, 5255.39562950258, 2115.1645100185647, 2158.892055397224, 2161.221960094365, 2161.567686462714, 2170.739511258659, 965.9661911833052, 1025.646152077532, 1079.519613771864, 1092.5083712329324, 1110.1285490889613, 20694.166385855104, 21182.894085529475, 21227.0, 21277.78722212789, 21618.567628647652, 1006.335818359879, 1012.9341592796445, 1024.0936770957755, 1031.118393773328, 1083.8970297821695, 10845.069016797499, 10903.727406814734, 11810.232334898204, 11857.407129643483, 12109.576164834189, 547960.0, 556616.0, 566581.0, 581259.0, 587014.0, 98870.0, 101655.0, 102376.48811756042, 102985.48507257566, 103445.48277258566, 1053.8155822731144, 1057.2018126314738, 1058.4760543531002, 1066.3098988103943, 1072.1904961753826, 18617.81382186215, 18833.623327533645, 19098.904505477385, 19499.317523886595, 19521.902390487958, 32940.67059329472, 45869.85325366892, 47233.527664724286, 47318.76340618412, 48564.0, 64854.35145648483, 65342.0, 65485.34514654792, 65978.67010664917, 66641.66679166573, 9062.592183351633, 9114.225290850087, 9149.817003660022, 9299.488528131018, 9512.95243523778, 10191.94904025475, 12710.0, 13054.0, 13826.239556824472, 14408.0, 10835.729106772078, 11361.37512436824, 11513.53945842157, 11524.250923689933, 11558.94220528892, 12155.331456769962, 12173.330466824409, 12377.62867113988, 12431.502739890304, 12580.30808305552, 10886.40124793144, 11095.278806877526, 11134.498947547218, 11248.775024499628, 11410.201285909907, 8045.678172873254, 8298.70954516589, 9032.819343613222, 9386.953065234904, 9522.761930951505, 8781.91218087811, 8843.602037908438, 8847.955760221157, 9068.818623627623, 9194.264458843143], "value": 9632.889728189402, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.8996270261086, 1520.764281536362, 1587.7856489373933, 1764.964700705986, 1901.2109974360633, 8516.531590762506, 9568.904310956897, 9784.510774461267, 9808.705738827837, 10201.642942497014, 9950.353227040237, 10020.24848136391, 10049.196064314856, 10055.396676199414, 10057.899421005788, 2532.8227024108273, 2556.6037264224074, 2559.0403598650505, 2561.167620523328, 2562.128876182098, 2485.1923124984364, 2580.561304578226, 2586.5473542130144, 2588.3787890906237, 2590.702069262031, 2561.2572354017407, 2564.7307032761573, 2595.4679290745353, 2608.4652646207483, 2609.125942809161, 2483.7640424159677, 2518.4837108392826, 2518.9874050629815, 2527.45659683169, 2529.140092368594, 2429.380507970474, 2442.0842184180933, 2446.926592202237, 2487.4776296977657, 2535.264773215765, 9549.522523873848, 9794.9510252449, 10004.899951000469, 10013.098821106063, 10033.046860548237, 5951.58338916276, 6108.083787431877, 6120.418560236815, 6121.755129794848, 6122.05108208224, 3594.7663401878795, 3644.671979521856, 3649.50731651229, 3673.981630091859, 3773.8490460381554, 1229.2133034857698, 1233.173773571709, 1237.9442925068392, 1255.2844878419305, 1262.9684257893534, 14331.211783351911, 14342.067765595202, 14345.354459049368, 14364.353604087737, 14374.20941848198, 4951.108800415887, 5154.587632989352, 5254.133068043807, 5259.921101183485, 5261.053010458078, 11058.11535077192, 11078.446077696244, 11103.611373601885, 11109.44452777358, 11145.888541114648, 5966.343702192756, 5966.76132954677, 5971.193888824953, 5974.85062873423, 5978.282606087298, 4413.009666686352, 4673.556012178838, 4818.807247710122, 5416.783328666888, 5553.861153471114, 4265.210935976854, 4266.8293268269545, 4338.132373525307, 4365.039691267916, 4386.517483076859, 1254.9811752823532, 1255.5480027190204, 1261.8990480761518, 1265.9240445573296, 1272.465564462928, 900.0238416911711, 987.8370068938689, 992.9404235745878, 1043.0612448795991, 1049.9842502362471, 6382.36176382358, 6388.39310265524, 6418.711157997901, 6525.4779617630475, 6582.17722784652, 503.66506273327826, 506.7922151917696, 512.9102407078747, 514.0336168004203, 516.5351183934489, 4435.046465010038, 4440.444944381952, 4507.549245075532, 4615.746133962664, 4701.506341834127, 12743.299118548568, 12758.489660413665, 12881.806772898413, 12892.613221603367, 12906.35468226585, 11992.700182495508, 12543.937280313723, 12780.169288996185, 12810.039247056322, 12824.679383015313, 9036.141566551298, 9183.862242066241, 9234.353595248269, 9241.214496767716, 9245.491497967541, 6437.710303036468, 6446.161998940295, 6450.193725784277, 6538.248101468202, 6656.83357916056, 10997.945010274898, 11719.824202636797, 11748.823767643322, 11837.526498939946, 11892.286462812259, 10453.425061621683, 11363.31820090764, 11549.0, 11695.649130526097, 11831.526738930346, 8279.875801863098, 8665.523396213268, 8739.344549158966, 8743.562821858754, 8770.38607297483, 9661.372010819274, 9770.267229957924, 9793.951030244803, 9802.852957205505, 9808.215342772419, 8691.521966291915, 8814.5592720365, 8826.117388261122, 8905.269150673552, 9056.683016094405, 8553.360390658725, 8630.136986301319, 8689.739307820775, 8746.606402711766, 8830.867536987078, 7827.178146294557, 7863.882041769263, 7904.13054563986, 7917.2874441300555, 7949.205079492004, 5575.832725018256, 5593.804216852391, 5686.317641883001, 5777.277840269966, 5821.380378829628, 5575.749091290821, 5736.598438109292, 5765.827025189251, 5782.913256301074, 5829.970850145722], "value": 5040.801376482047, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.2894894415393, 349.94750787381895, 354.24192228630733, 355.2982858853766, 356.34966186709255, 5606.271184745982, 5683.5169010634045, 5690.174924635925, 5711.171880077386, 5718.456746609076, 5527.309086364205, 5547.944520554794, 5624.15637654351, 5636.0982242841155, 5652.745626446809, 3907.960920390788, 4139.99850108671, 4364.345348197764, 4400.339949007658, 4411.360352748866, 4374.387585737999, 4386.8903277418, 4397.582229688191, 4402.691811573191, 4411.955880441203, 4391.560843915615, 4399.736015839044, 4406.515283318832, 4413.484313941055, 4424.601785839274, 4209.200251952119, 4302.612764851178, 4363.694541382105, 4382.255016647177, 4402.669799765031, 3922.882313530599, 3925.1953349563273, 3943.7042221833476, 3948.7038472114705, 3967.6230758077936, 5430.266913966603, 5452.536534394581, 5482.738970036914, 5488.835334939959, 5502.202180683818, 4761.666683332168, 4994.60043196547, 5015.824446144404, 5028.1954887218235, 5084.13569693153, 5794.710264486801, 5820.738066786962, 5825.417458254183, 5841.88316233673, 5843.328017278025, 4264.424302719124, 4339.435873336478, 4361.672874534423, 4376.715513491613, 4382.9342559861625, 5728.713564321767, 5875.177475153482, 6058.848528786772, 6067.512319948844, 6091.969540152271, 4379.562043795659, 4394.384786129945, 4409.7795110245, 4411.117776444724, 4415.183191109655, 4471.128130014616, 4486.461624605068, 4489.842855500042, 4492.05666809967, 4494.977525112354, 4835.540623640749, 4987.202047672355, 5050.671706339076, 5082.33929589151, 5099.847004589868, 5777.2200752897825, 5799.449052340021, 5806.04200306953, 5842.444967728059, 5881.264841894763, 1516.097921736587, 1542.9122468659793, 1543.6989786991428, 1544.5211984284908, 1550.7751376050635, 973.9464329461806, 1014.3660212367271, 1015.1574193419444, 1020.336781092296, 1042.0673497219946, 4972.428170760409, 5013.949860501421, 5058.291839142523, 5064.41759197697, 5074.949250507448, 5250.403473620582, 5259.868503287372, 5268.841934741964, 5282.181261904411, 5313.335833020872, 4813.229883218668, 5035.77339019745, 5067.1758167059825, 5068.594512438996, 5073.644844860898, 2811.0301945828655, 2816.9859150704115, 2823.8305701657555, 2826.307554649124, 2828.886844526237, 5432.772563249128, 5465.808696695596, 5470.781168753285, 5493.533049690741, 5494.675721188623, 4981.6014718823135, 5215.608829337739, 5332.733363331818, 5340.626156169109, 5371.892562148735, 5357.892842143136, 5369.275147855068, 5394.786173111019, 5396.487333703292, 5404.972975135099, 5227.790888364575, 5249.265102885675, 5282.709450980233, 5313.362396512289, 5339.0389729849, 5438.6947132688665, 5836.066229403275, 5864.179014937827, 5874.559408044328, 5898.144769008555, 5908.577483231854, 6018.699065046818, 6130.846728831815, 6188.907166392418, 6207.0, 4326.437563116775, 4353.259945809284, 4387.539308372575, 4393.275109607007, 4399.47206335229, 5750.568707347049, 6042.643369086148, 6049.758009679564, 6102.481289090477, 6108.664023478751, 5809.82570522885, 5886.381929897299, 5895.233619629421, 5895.9115613266695, 5902.822915312548, 5875.441833025856, 5898.970505147447, 5911.527077833849, 5918.40815918422, 5928.822135335947, 5700.971495142498, 5821.359650438364, 5843.7370318336625, 5855.795047173328, 5869.383714710065, 5913.29040515141, 5923.0, 5925.05199168131, 5960.612560183574, 5961.791337303173, 5664.291963504562, 5732.111522713986, 5747.48272655463, 5787.421257874179, 5810.6513609183585], "value": 4370.180035375876, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.1279273609923, 796.4902462424049, 801.0467543623088, 801.5551368990208, 940.7318914109478, 13966.16203027819, 14802.111873287611, 14823.999999999987, 14913.403463861432, 14940.626484337898, 14072.0, 14208.147511149313, 14275.785863212033, 14278.857211427883, 14287.642808929782, 744.7058411927286, 845.2603971524916, 849.3672214200413, 869.2003356911625, 876.9868451973225, 893.3880291999968, 895.5656506594297, 895.6775560798108, 895.8118795053047, 898.0704970355683, 931.7065124485799, 935.504182783126, 936.6768464879606, 938.0947385772728, 938.53542496464, 779.7777633374461, 798.9840203195932, 894.4186278918689, 922.4004397141845, 933.2300851797261, 679.7450955891541, 684.7979845945458, 685.3215316836346, 685.7017197519045, 686.5365878032309, 12867.742645147044, 12944.676383090498, 12977.935110324483, 12991.740165196738, 13052.28212448315, 14305.499307524187, 14440.927795361062, 14497.347619357155, 14525.92737036308, 14536.92731536346, 13745.587632371045, 13837.515686951008, 13900.235487048209, 14073.71852562943, 14166.150030998173, 4503.459584849839, 4550.135474259913, 4596.310553416981, 4620.237660785966, 4686.179918514281, 82781.58609206915, 83958.58020709918, 84783.5760821198, 85058.57470712668, 86769.13230867736, 10905.34567925927, 10977.945110274397, 11038.848464735089, 11121.443927803577, 11131.165162612837, 19598.314059007866, 19602.11790469432, 19607.705884411746, 19619.313324033876, 19621.901890490455, 8145.3483721302155, 8177.836443271101, 8296.460730052528, 8493.872591911126, 8837.955810220908, 4628.421447319085, 4631.652626053059, 4633.49031606523, 4639.0, 5056.721880296545, 3456.671616196508, 3562.5190599268767, 3566.4828599852876, 3584.7849129052343, 3589.94615080774, 649.4349915573481, 671.9563228390236, 680.7685386968454, 681.9761308354184, 703.8134894253027, 682.3585829320423, 692.3284414118298, 710.3393843725282, 725.5320318394712, 726.4478995963107, 8823.251064677634, 9067.637294508146, 9103.726888193365, 9142.588583513758, 9172.495512746864, 630.6941133550224, 676.1210426445601, 677.4072686399401, 689.0491122251261, 711.3562226185311, 4298.957010429918, 4403.3174857897075, 4418.690691651554, 4446.466424029137, 4503.909921801545, 103052.48473757734, 104742.47628761808, 108097.45951270193, 131161.0, 134227.32886335507, 61457.0, 73772.63113684398, 99517.50241248892, 99712.0, 100252.49873750584, 86078.0, 91577.0, 94407.33555986662, 94718.5264073689, 96643.0, 9188.632454702005, 9514.143727064597, 9582.760430989003, 9692.660756873758, 9740.707778766648, 54315.0, 54628.0, 55214.72392638011, 55886.441135589754, 56141.0, 47742.0, 47832.282515762985, 62807.6859615699, 76903.61548192223, 77163.22836771785, 11929.701757456132, 12508.186967847061, 12529.248245105322, 12647.367631618567, 13010.414531345923, 14390.56828295111, 14426.206558639376, 14603.780943286072, 14744.483943061941, 15359.539213823604, 11970.990910951225, 12290.20113692607, 12413.937930310653, 12648.43082061328, 13172.47310107585, 12204.450799714212, 12387.62837114888, 12428.689282768004, 12675.429605667583, 12958.546450874173, 11652.184347095961, 12087.274763514217, 12365.381730913597, 12413.503459861504, 12847.743045139232, 10925.617603383844, 10976.450002726408, 11485.196036277715, 11580.768384632429, 11711.824322635337, 10460.44606642408, 11007.559697612005, 11048.392338421463, 11053.226274161054, 11076.778464430827], "value": 7544.311887860989, "unit": "score"}], "name": "dromaeo_css", "value": 7568.620771840365, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 111.0, 111.0, 111.0, 109.0, 110.0, 110.0, 112.0, 110.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 115.0, 114.0, 170.0, 122.0, 118.0, 147.0, 126.0, 152.0, 119.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 160.0, 161.0, 161.0, 161.0, 162.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 109.0, 76.0, 75.0, 74.0, 75.0, 104.0, 75.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 132.0, 83.0, 84.0, 84.0, 111.0, 83.0, 84.0, 126.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 147.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 49.0, 49.0, 49.0, 49.0, 49.0, 50.0, 49.0, 50.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 58.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 72.0, 83.0, 72.0, 71.0, 72.0, 73.0, 73.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 123.0, 118.0, 115.0, 132.0, 114.0, 125.0, 117.0, 154.0, 117.0], "value": 123.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 152.0, 149.0, 151.0, 151.0, 151.0, 149.0, 149.0, 152.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 63.0, 61.0, 64.0, 62.0, 68.0, 59.0, 59.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1454.7, "alertThreshold": 2.0}]} 05:00:46 INFO - Return code: 0 05:00:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:00:46 INFO - # TBPL SUCCESS # 05:00:46 INFO - Running post-action listener: _resource_record_post_action 05:00:46 INFO - [mozharness: 2016-08-15 12:00:46.240374Z] Finished run-tests step (success) 05:00:46 INFO - Running post-run listener: _resource_record_post_run 05:00:46 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 329408512; Read time: 0; Write time: 218744 05:00:46 INFO - TinderboxPrint: CPU usage
13.0% 05:00:46 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:00:46 INFO - TinderboxPrint: I/O write bytes / time
329,408,512 / 218,744 05:00:46 INFO - TinderboxPrint: CPU idle
8,265.2 (87.0%) 05:00:46 INFO - TinderboxPrint: CPU user
1,188.0 (12.5%) 05:00:46 INFO - TinderboxPrint: Swap in / out
0 / 0 05:00:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79278080; Read time: 0; Write time: 42712 05:00:46 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250130432; Read time: 0; Write time: 176032 05:00:46 INFO - Running post-run listener: _upload_blobber_files 05:00:46 INFO - Blob upload gear active. 05:00:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:00:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:00:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:00:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:00:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:00:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:48 INFO - (blobuploader) - INFO - Done attempting. 05:00:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:00:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:52 INFO - (blobuploader) - INFO - Done attempting. 05:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:00:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:00:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:00:52 INFO - (blobuploader) - INFO - Done attempting. 05:00:52 INFO - (blobuploader) - INFO - Iteration through files over. 05:00:52 INFO - Return code: 0 05:00:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:00:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:00:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8478975bf32afc58df2d820814d6869ba7ebdb495af2ce0ba4b7e9f0a6ed4f785e0cac1ad7d9f4470b6e85f9e0348eb4e5028739a89b8f942a9b8cf3c5e2ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57bdebcafe5ff1e219ebc8cd42bf7a943d65455d58474d9c1d576cf7f734eb804a7b8cccd7914490fdb4b2422ad9abf8ab208f06fb4110d0255fc888da2641cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b818e23b745c063b4437720bc074d03f8874dda5a27f357aa7dd35de99be862e5e78042e3fd91a30a2db70c33a1d84e87c8163216d496d58b097e3b52840753"} 05:00:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:00:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:00:52 INFO - Contents: 05:00:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad8478975bf32afc58df2d820814d6869ba7ebdb495af2ce0ba4b7e9f0a6ed4f785e0cac1ad7d9f4470b6e85f9e0348eb4e5028739a89b8f942a9b8cf3c5e2ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57bdebcafe5ff1e219ebc8cd42bf7a943d65455d58474d9c1d576cf7f734eb804a7b8cccd7914490fdb4b2422ad9abf8ab208f06fb4110d0255fc888da2641cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b818e23b745c063b4437720bc074d03f8874dda5a27f357aa7dd35de99be862e5e78042e3fd91a30a2db70c33a1d84e87c8163216d496d58b097e3b52840753"} 05:00:52 INFO - Running post-run listener: copy_logs_to_upload_dir 05:00:52 INFO - Copying logs to upload dir... 05:00:52 INFO - mkdir: /builds/slave/test/build/upload/logs 05:00:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.806500 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-15 05:00:52.927792) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 05:00:52.931843) ========= 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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 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/ad8478975bf32afc58df2d820814d6869ba7ebdb495af2ce0ba4b7e9f0a6ed4f785e0cac1ad7d9f4470b6e85f9e0348eb4e5028739a89b8f942a9b8cf3c5e2ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57bdebcafe5ff1e219ebc8cd42bf7a943d65455d58474d9c1d576cf7f734eb804a7b8cccd7914490fdb4b2422ad9abf8ab208f06fb4110d0255fc888da2641cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b818e23b745c063b4437720bc074d03f8874dda5a27f357aa7dd35de99be862e5e78042e3fd91a30a2db70c33a1d84e87c8163216d496d58b097e3b52840753"} build_url:https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011081 build_url: 'https://queue.taskcluster.net/v1/task/Flf0mpjSS3KHx9D2Avsy5g/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/ad8478975bf32afc58df2d820814d6869ba7ebdb495af2ce0ba4b7e9f0a6ed4f785e0cac1ad7d9f4470b6e85f9e0348eb4e5028739a89b8f942a9b8cf3c5e2ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57bdebcafe5ff1e219ebc8cd42bf7a943d65455d58474d9c1d576cf7f734eb804a7b8cccd7914490fdb4b2422ad9abf8ab208f06fb4110d0255fc888da2641cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b818e23b745c063b4437720bc074d03f8874dda5a27f357aa7dd35de99be862e5e78042e3fd91a30a2db70c33a1d84e87c8163216d496d58b097e3b52840753"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 05:00:52.957794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:00:52.958245) ========= 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=232498196343f89f466a4a9a000001c8-1471259963.680377-1508192990 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 05:00:53.044577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 05:00:53.045073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 05:00:53.045576) ========= ========= Total master_lag: 0.18 =========