builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1471171491.06 results: success (0) buildid: 20160814004012 builduid: ae69be8db4b34863bd6265b754f4b767 revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.057910) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.058394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.058701) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.086867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.087218) ========= 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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-14 03:44:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-08-14 03:44:52 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126478 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.248314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.248689) ========= 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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019633 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.303112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.303749) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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 a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-14 03:44:53,135 truncating revision to first 12 chars 2016-08-14 03:44:53,136 Setting DEBUG logging. 2016-08-14 03:44:53,136 attempt 1/10 2016-08-14 03:44:53,136 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a6fcbbeaddff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-14 03:44:53,436 unpacking tar archive at: mozilla-aurora-a6fcbbeaddff/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636894 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.960878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.961156) ========= script_repo_revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.961532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.961803) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-14 03:44:51.973295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-14 03:44:51.973576) ========= /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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:44:53 INFO - MultiFileLogger online at 20160814 03:44:53 in /builds/slave/test 03:44:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:44:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:44:53 INFO - {'append_to_log': False, 03:44:53 INFO - 'base_work_dir': '/builds/slave/test', 03:44:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:44:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:44:53 INFO - 'branch': 'Mozilla-Aurora', 03:44:53 INFO - 'buildbot_json_path': 'buildprops.json', 03:44:53 INFO - 'config_files': ('talos/linux_config.py',), 03:44:53 INFO - 'default_actions': ('clobber', 03:44:53 INFO - 'read-buildbot-config', 03:44:53 INFO - 'download-and-extract', 03:44:53 INFO - 'populate-webroot', 03:44:53 INFO - 'create-virtualenv', 03:44:53 INFO - 'install', 03:44:53 INFO - 'run-tests'), 03:44:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:44:53 INFO - 'download_minidump_stackwalk': True, 03:44:53 INFO - 'download_symbols': 'ondemand', 03:44:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:44:53 INFO - 'tooltool.py': '/tools/tooltool.py', 03:44:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:44:53 INFO - '/tools/misc-python/virtualenv.py')}, 03:44:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:44:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:44:53 INFO - 'installer_path': 'installer.exe', 03:44:53 INFO - 'log_level': 'info', 03:44:53 INFO - 'log_name': 'talos', 03:44:53 INFO - 'log_to_console': True, 03:44:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:44:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:44:53 INFO - 'opt_config_files': (), 03:44:53 INFO - 'pip_index': False, 03:44:53 INFO - 'sps_profile': False, 03:44:53 INFO - 'sps_profile_interval': 0, 03:44:53 INFO - 'suite': 'dromaeojs', 03:44:53 INFO - 'system_bits': '32', 03:44:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:44:53 INFO - 'title': 'talos-linux64-ix-065', 03:44:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:44:53 INFO - 'use_talos_json': True, 03:44:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:44:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:44:53 INFO - 'work_dir': 'build'} 03:44:53 INFO - [mozharness: 2016-08-14 10:44:53.903007Z] Running clobber step. 03:44:53 INFO - Running pre-action listener: _resource_record_pre_action 03:44:53 INFO - Running main action method: clobber 03:44:53 INFO - rmtree: /builds/slave/test/build 03:44:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:44:54 INFO - Running post-action listener: _resource_record_post_action 03:44:54 INFO - [mozharness: 2016-08-14 10:44:54.869892Z] Finished clobber step (success) 03:44:54 INFO - [mozharness: 2016-08-14 10:44:54.869993Z] Running read-buildbot-config step. 03:44:54 INFO - Running pre-action listener: _resource_record_pre_action 03:44:54 INFO - Running main action method: read_buildbot_config 03:44:54 INFO - Using buildbot properties: 03:44:54 INFO - { 03:44:54 INFO - "project": "", 03:44:54 INFO - "product": "firefox", 03:44:54 INFO - "script_repo_revision": "production", 03:44:54 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:44:54 INFO - "repository": "", 03:44:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:44:54 INFO - "buildid": "20160814004012", 03:44:54 INFO - "stage_platform": "linux64-pgo", 03:44:54 INFO - "basedir": "/builds/slave/test", 03:44:54 INFO - "nightly_build": "True", 03:44:54 INFO - "buildnumber": 466, 03:44:54 INFO - "slavename": "talos-linux64-ix-065", 03:44:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:44:54 INFO - "platform": "ubuntu64_hw", 03:44:54 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:44:54 INFO - "branch": "mozilla-aurora", 03:44:54 INFO - "repo_path": "releases/mozilla-aurora", 03:44:54 INFO - "revision": "a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5", 03:44:54 INFO - "pgo_build": "True", 03:44:54 INFO - "builduid": "ae69be8db4b34863bd6265b754f4b767", 03:44:54 INFO - "slavebuilddir": "test" 03:44:54 INFO - } 03:44:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:44:54 INFO - Running post-action listener: _resource_record_post_action 03:44:54 INFO - [mozharness: 2016-08-14 10:44:54.872501Z] Finished read-buildbot-config step (success) 03:44:54 INFO - [mozharness: 2016-08-14 10:44:54.872589Z] Running download-and-extract step. 03:44:54 INFO - Running pre-action listener: _resource_record_pre_action 03:44:54 INFO - Running main action method: download_and_extract 03:44:54 INFO - mkdir: /builds/slave/test/build/tests 03:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:44:54 INFO - https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:44:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:44:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:44:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:44:54 INFO - retry: Failed, sleeping 30 seconds before retrying 03:45:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:45:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:45:24 INFO - retry: Failed, sleeping 60 seconds before retrying 03:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:46:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:46:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:46:24 INFO - Caught exception: HTTP Error 404: Not Found 03:46:24 INFO - Caught exception: HTTP Error 404: Not Found 03:46:24 INFO - Caught exception: HTTP Error 404: Not Found 03:46:24 INFO - trying https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:24 INFO - Downloading https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:46:25 INFO - Downloaded 1361 bytes. 03:46:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:25 INFO - Using the following test package requirements: 03:46:25 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:25 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:25 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:46:25 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'jsshell-linux-x86_64.zip'], 03:46:25 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:25 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:25 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:46:25 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:46:25 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:25 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:25 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:25 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:25 INFO - https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:46:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:46:25 INFO - retry: Failed, sleeping 30 seconds before retrying 03:46:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:46:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:46:55 INFO - retry: Failed, sleeping 60 seconds before retrying 03:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:47:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:47:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:47:55 INFO - Caught exception: HTTP Error 404: Not Found 03:47:55 INFO - Caught exception: HTTP Error 404: Not Found 03:47:55 INFO - Caught exception: HTTP Error 404: Not Found 03:47:55 INFO - trying https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:55 INFO - Downloading https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:47:56 INFO - Downloaded 21767873 bytes. 03:47:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:47:57 INFO - Return code: 0 03:47:57 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:57 INFO - https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:47:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:47:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:47:57 INFO - retry: Failed, sleeping 30 seconds before retrying 03:48:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:48:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:48:27 INFO - retry: Failed, sleeping 60 seconds before retrying 03:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:49:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:49:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:49:27 INFO - Caught exception: HTTP Error 404: Not Found 03:49:27 INFO - Caught exception: HTTP Error 404: Not Found 03:49:27 INFO - Caught exception: HTTP Error 404: Not Found 03:49:27 INFO - trying https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:27 INFO - Downloading https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:49:28 INFO - Downloaded 21767873 bytes. 03:49:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:49:30 INFO - Return code: 0 03:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:30 INFO - https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:49:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:49:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:30 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:50:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:50:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:00 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:51:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:51:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:51:00 INFO - Caught exception: HTTP Error 404: Not Found 03:51:00 INFO - Caught exception: HTTP Error 404: Not Found 03:51:00 INFO - Caught exception: HTTP Error 404: Not Found 03:51:00 INFO - trying https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:51:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:51:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:51:02 INFO - Downloaded 14413723 bytes. 03:51:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:51:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:51:02 INFO - Return code: 0 03:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:02 INFO - https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:51:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:51:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:51:02 INFO - retry: Failed, sleeping 30 seconds before retrying 03:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:51:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:51:32 INFO - retry: Failed, sleeping 60 seconds before retrying 03:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:52:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:52:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:52:32 INFO - Caught exception: HTTP Error 404: Not Found 03:52:32 INFO - Caught exception: HTTP Error 404: Not Found 03:52:32 INFO - Caught exception: HTTP Error 404: Not Found 03:52:32 INFO - trying https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:52:33 INFO - Downloaded 63743979 bytes. 03:52:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:33 INFO - mkdir: /builds/slave/test/properties 03:52:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:33 INFO - Contents: 03:52:33 INFO - build_url:https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:34 INFO - Running post-action listener: _resource_record_post_action 03:52:34 INFO - Running post-action listener: set_extra_try_arguments 03:52:34 INFO - [mozharness: 2016-08-14 10:52:34.489820Z] Finished download-and-extract step (success) 03:52:34 INFO - [mozharness: 2016-08-14 10:52:34.490051Z] Running populate-webroot step. 03:52:34 INFO - Running pre-action listener: _resource_record_pre_action 03:52:34 INFO - Running main action method: populate_webroot 03:52:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:34 INFO - '--tppagecycles', 03:52:34 INFO - '7'], 03:52:34 INFO - 'tests': ['tsvgm']}}, 03:52:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['damp', 'tps']}, 03:52:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'tests': ['damp', 'tps']}, 03:52:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['dromaeo_dom']}, 03:52:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['basic_compositor_video']}, 03:52:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['a11yr', 03:52:34 INFO - 'ts_paint', 03:52:34 INFO - 'tpaint', 03:52:34 INFO - 'sessionrestore', 03:52:34 INFO - 'sessionrestore_no_auto_restore', 03:52:34 INFO - 'tabpaint']}, 03:52:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['tsvgx', 03:52:34 INFO - 'tsvgr_opacity', 03:52:34 INFO - 'tart', 03:52:34 INFO - 'tscrollx', 03:52:34 INFO - 'cart']}, 03:52:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:34 INFO - 'tsvgr_opacity', 03:52:34 INFO - 'tart', 03:52:34 INFO - 'tscrollx', 03:52:34 INFO - 'cart']}, 03:52:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'talos_options': ['--disable-e10s'], 03:52:34 INFO - 'tests': ['tp5o']}, 03:52:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'tests': ['tp5o']}, 03:52:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'talos_options': ['--disable-e10s', 03:52:34 INFO - '--xperf_path', 03:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:34 INFO - 'tests': ['tp5n']}, 03:52:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:34 INFO - 'talos_options': ['--xperf_path', 03:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:34 INFO - 'tests': ['tp5n']}}, 03:52:34 INFO - 'talos.zip': {'path': '', 03:52:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:34 INFO - Running post-action listener: _resource_record_post_action 03:52:34 INFO - [mozharness: 2016-08-14 10:52:34.509167Z] Finished populate-webroot step (success) 03:52:34 INFO - [mozharness: 2016-08-14 10:52:34.509256Z] Running create-virtualenv step. 03:52:34 INFO - Running pre-action listener: _resource_record_pre_action 03:52:34 INFO - Running main action method: create_virtualenv 03:52:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52: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 03:52:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:34 INFO - Using real prefix '/usr' 03:52:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:37 INFO - Installing pip.................done. 03:52:37 INFO - Return code: 0 03:52:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:52:37 INFO - Using env: {'DISPLAY': ':0', 03:52:37 INFO - 'HOME': '/home/cltbld', 03:52:37 INFO - 'LANG': 'en_US.UTF-8', 03:52:37 INFO - 'LANGUAGE': 'en_US:en', 03:52:37 INFO - 'LOGNAME': 'cltbld', 03:52:37 INFO - 'MAIL': '/var/mail/cltbld', 03:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:37 INFO - 'MOZ_NO_REMOTE': '1', 03:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:37 INFO - 'NO_EM_RESTART': '1', 03:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:37 INFO - 'PWD': '/builds/slave/test', 03:52:37 INFO - 'SHELL': '/bin/bash', 03:52:37 INFO - 'SHLVL': '1', 03:52:37 INFO - 'TERM': 'linux', 03:52:37 INFO - 'TMOUT': '86400', 03:52:37 INFO - 'USER': 'cltbld', 03:52:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:37 INFO - '_': '/tools/buildbot/bin/python'} 03:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:52:37 INFO - Cleaning up... 03:52:37 INFO - Return code: 0 03:52:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:52:37 INFO - Using env: {'DISPLAY': ':0', 03:52:37 INFO - 'HOME': '/home/cltbld', 03:52:37 INFO - 'LANG': 'en_US.UTF-8', 03:52:37 INFO - 'LANGUAGE': 'en_US:en', 03:52:37 INFO - 'LOGNAME': 'cltbld', 03:52:37 INFO - 'MAIL': '/var/mail/cltbld', 03:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:37 INFO - 'MOZ_NO_REMOTE': '1', 03:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:37 INFO - 'NO_EM_RESTART': '1', 03:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:37 INFO - 'PWD': '/builds/slave/test', 03:52:37 INFO - 'SHELL': '/bin/bash', 03:52:37 INFO - 'SHLVL': '1', 03:52:37 INFO - 'TERM': 'linux', 03:52:37 INFO - 'TMOUT': '86400', 03:52:37 INFO - 'USER': 'cltbld', 03:52:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:37 INFO - '_': '/tools/buildbot/bin/python'} 03:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:37 INFO - Downloading/unpacking psutil>=3.1.1 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:39 INFO - Installing collected packages: psutil 03:52:39 INFO - Running setup.py install for psutil 03:52:39 INFO - building 'psutil._psutil_linux' extension 03:52: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 03:52: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 03:52:39 INFO - building 'psutil._psutil_posix' extension 03:52: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 03:52: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 03:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:39 INFO - Successfully installed psutil 03:52:39 INFO - Cleaning up... 03:52:40 INFO - Return code: 0 03:52:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:52:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:52:40 INFO - Using env: {'DISPLAY': ':0', 03:52:40 INFO - 'HOME': '/home/cltbld', 03:52:40 INFO - 'LANG': 'en_US.UTF-8', 03:52:40 INFO - 'LANGUAGE': 'en_US:en', 03:52:40 INFO - 'LOGNAME': 'cltbld', 03:52:40 INFO - 'MAIL': '/var/mail/cltbld', 03:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:40 INFO - 'MOZ_NO_REMOTE': '1', 03:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:40 INFO - 'NO_EM_RESTART': '1', 03:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:40 INFO - 'PWD': '/builds/slave/test', 03:52:40 INFO - 'SHELL': '/bin/bash', 03:52:40 INFO - 'SHLVL': '1', 03:52:40 INFO - 'TERM': 'linux', 03:52:40 INFO - 'TMOUT': '86400', 03:52:40 INFO - 'USER': 'cltbld', 03:52:40 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:40 INFO - '_': '/tools/buildbot/bin/python'} 03:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:52:42 INFO - Installing collected packages: mozsystemmonitor 03:52:42 INFO - Running setup.py install for mozsystemmonitor 03:52:42 INFO - Successfully installed mozsystemmonitor 03:52:42 INFO - Cleaning up... 03:52:42 INFO - Return code: 0 03:52:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:52:42 INFO - Using env: {'DISPLAY': ':0', 03:52:42 INFO - 'HOME': '/home/cltbld', 03:52:42 INFO - 'LANG': 'en_US.UTF-8', 03:52:42 INFO - 'LANGUAGE': 'en_US:en', 03:52:42 INFO - 'LOGNAME': 'cltbld', 03:52:42 INFO - 'MAIL': '/var/mail/cltbld', 03:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:42 INFO - 'MOZ_NO_REMOTE': '1', 03:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:42 INFO - 'NO_EM_RESTART': '1', 03:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:42 INFO - 'PWD': '/builds/slave/test', 03:52:42 INFO - 'SHELL': '/bin/bash', 03:52:42 INFO - 'SHLVL': '1', 03:52:42 INFO - 'TERM': 'linux', 03:52:42 INFO - 'TMOUT': '86400', 03:52:42 INFO - 'USER': 'cltbld', 03:52:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:42 INFO - '_': '/tools/buildbot/bin/python'} 03:52:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:52:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:44 INFO - Downloading functools32-3.2.3-2.tar.gz 03:52:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:52:44 INFO - warning: no files found matching '*.txt' 03:52:44 INFO - no previously-included directories found matching 'build' 03:52:44 INFO - no previously-included directories found matching 'dist' 03:52:44 INFO - no previously-included directories found matching '.git*' 03:52:44 INFO - Installing collected packages: jsonschema, functools32 03:52:44 INFO - Running setup.py install for functools32 03:52:44 INFO - warning: no files found matching '*.txt' 03:52:44 INFO - no previously-included directories found matching 'build' 03:52:44 INFO - no previously-included directories found matching 'dist' 03:52:44 INFO - no previously-included directories found matching '.git*' 03:52:44 INFO - Successfully installed jsonschema functools32 03:52:44 INFO - Cleaning up... 03:52:44 INFO - Return code: 0 03:52:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52: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 03:52: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 03:52:44 INFO - Using env: {'DISPLAY': ':0', 03:52:44 INFO - 'HOME': '/home/cltbld', 03:52:44 INFO - 'LANG': 'en_US.UTF-8', 03:52:44 INFO - 'LANGUAGE': 'en_US:en', 03:52:44 INFO - 'LOGNAME': 'cltbld', 03:52:44 INFO - 'MAIL': '/var/mail/cltbld', 03:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:44 INFO - 'MOZ_NO_REMOTE': '1', 03:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:44 INFO - 'NO_EM_RESTART': '1', 03:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:44 INFO - 'PWD': '/builds/slave/test', 03:52:44 INFO - 'SHELL': '/bin/bash', 03:52:44 INFO - 'SHLVL': '1', 03:52:44 INFO - 'TERM': 'linux', 03:52:44 INFO - 'TMOUT': '86400', 03:52:44 INFO - 'USER': 'cltbld', 03:52:44 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:44 INFO - '_': '/tools/buildbot/bin/python'} 03:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:52:45 INFO - Cleaning up... 03:52:45 INFO - Return code: 0 03:52:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:45 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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:52:45 INFO - Using env: {'DISPLAY': ':0', 03:52:45 INFO - 'HOME': '/home/cltbld', 03:52:45 INFO - 'LANG': 'en_US.UTF-8', 03:52:45 INFO - 'LANGUAGE': 'en_US:en', 03:52:45 INFO - 'LOGNAME': 'cltbld', 03:52:45 INFO - 'MAIL': '/var/mail/cltbld', 03:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:45 INFO - 'MOZ_NO_REMOTE': '1', 03:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:45 INFO - 'NO_EM_RESTART': '1', 03:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:45 INFO - 'PWD': '/builds/slave/test', 03:52:45 INFO - 'SHELL': '/bin/bash', 03:52:45 INFO - 'SHLVL': '1', 03:52:45 INFO - 'TERM': 'linux', 03:52:45 INFO - 'TMOUT': '86400', 03:52:45 INFO - 'USER': 'cltbld', 03:52:45 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:45 INFO - '_': '/tools/buildbot/bin/python'} 03:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:45 INFO - Downloading/unpacking blobuploader==1.2.4 03:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:47 INFO - Downloading blobuploader-1.2.4.tar.gz 03:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:52:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:52:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52: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 03:52:47 INFO - Downloading docopt-0.6.1.tar.gz 03:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:52:47 INFO - Installing collected packages: blobuploader, requests, docopt 03:52:47 INFO - Running setup.py install for blobuploader 03:52:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:52:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:52:48 INFO - Running setup.py install for requests 03:52:48 INFO - Running setup.py install for docopt 03:52:48 INFO - Successfully installed blobuploader requests docopt 03:52:48 INFO - Cleaning up... 03:52:48 INFO - Return code: 0 03:52:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52: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 03:52: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 03:52:48 INFO - Using env: {'DISPLAY': ':0', 03:52:48 INFO - 'HOME': '/home/cltbld', 03:52:48 INFO - 'LANG': 'en_US.UTF-8', 03:52:48 INFO - 'LANGUAGE': 'en_US:en', 03:52:48 INFO - 'LOGNAME': 'cltbld', 03:52:48 INFO - 'MAIL': '/var/mail/cltbld', 03:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:48 INFO - 'MOZ_NO_REMOTE': '1', 03:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:48 INFO - 'NO_EM_RESTART': '1', 03:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:48 INFO - 'PWD': '/builds/slave/test', 03:52:48 INFO - 'SHELL': '/bin/bash', 03:52:48 INFO - 'SHLVL': '1', 03:52:48 INFO - 'TERM': 'linux', 03:52:48 INFO - 'TMOUT': '86400', 03:52:48 INFO - 'USER': 'cltbld', 03:52:48 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:48 INFO - '_': '/tools/buildbot/bin/python'} 03:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:48 INFO - Running setup.py (path:/tmp/pip-GuhlBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:48 INFO - Running setup.py (path:/tmp/pip-bZKAi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:48 INFO - Running setup.py (path:/tmp/pip-KsUH2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:49 INFO - Running setup.py (path:/tmp/pip-VacK7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:49 INFO - Running setup.py (path:/tmp/pip-GJEciC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:49 INFO - Running setup.py (path:/tmp/pip-FP52Lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:49 INFO - Running setup.py (path:/tmp/pip-NMHh_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:49 INFO - Running setup.py (path:/tmp/pip-_Ubhxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:49 INFO - Running setup.py (path:/tmp/pip-hwqvi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:49 INFO - Running setup.py (path:/tmp/pip-8aP1kV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:49 INFO - Running setup.py (path:/tmp/pip-Kjx0fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:49 INFO - Running setup.py (path:/tmp/pip-YaynNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:49 INFO - Running setup.py (path:/tmp/pip-CH3RrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:49 INFO - Running setup.py (path:/tmp/pip-R7GI6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:49 INFO - Running setup.py (path:/tmp/pip-ucXeZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:49 INFO - Running setup.py (path:/tmp/pip-nX85ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:49 INFO - Running setup.py (path:/tmp/pip-x2ozsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:52:50 INFO - Running setup.py install for manifestparser 03:52:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Running setup.py install for mozcrash 03:52:50 INFO - Running setup.py install for mozdebug 03:52:50 INFO - Running setup.py install for mozdevice 03:52:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Running setup.py install for mozfile 03:52:50 INFO - Running setup.py install for mozhttpd 03:52:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Running setup.py install for mozinfo 03:52:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Running setup.py install for mozInstall 03:52:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:52:50 INFO - Running setup.py install for mozleak 03:52:50 INFO - Running setup.py install for mozlog 03:52:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Running setup.py install for moznetwork 03:52:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Running setup.py install for mozprocess 03:52:51 INFO - Running setup.py install for mozprofile 03:52:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Running setup.py install for mozrunner 03:52:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Running setup.py install for mozscreenshot 03:52:51 INFO - Running setup.py install for moztest 03:52:51 INFO - Running setup.py install for mozversion 03:52:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:52:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:52:51 INFO - Cleaning up... 03:52:51 INFO - Return code: 0 03:52:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52: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 03:52: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 03:52:51 INFO - Using env: {'DISPLAY': ':0', 03:52:51 INFO - 'HOME': '/home/cltbld', 03:52:51 INFO - 'LANG': 'en_US.UTF-8', 03:52:51 INFO - 'LANGUAGE': 'en_US:en', 03:52:51 INFO - 'LOGNAME': 'cltbld', 03:52:51 INFO - 'MAIL': '/var/mail/cltbld', 03:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:51 INFO - 'MOZ_NO_REMOTE': '1', 03:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:51 INFO - 'NO_EM_RESTART': '1', 03:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:51 INFO - 'PWD': '/builds/slave/test', 03:52:51 INFO - 'SHELL': '/bin/bash', 03:52:51 INFO - 'SHLVL': '1', 03:52:51 INFO - 'TERM': 'linux', 03:52:51 INFO - 'TMOUT': '86400', 03:52:51 INFO - 'USER': 'cltbld', 03:52:51 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:51 INFO - '_': '/tools/buildbot/bin/python'} 03:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:52:52 INFO - Running setup.py (path:/tmp/pip-WYNz2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:52:52 INFO - Running setup.py (path:/tmp/pip-WOaqhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:52:52 INFO - Running setup.py (path:/tmp/pip-TdgdMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:52:52 INFO - Running setup.py (path:/tmp/pip-BrbQga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:52:52 INFO - Running setup.py (path:/tmp/pip-zrwioZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:52:52 INFO - Running setup.py (path:/tmp/pip-rtZSTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:52:52 INFO - Running setup.py (path:/tmp/pip-c4dAri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:52:52 INFO - Running setup.py (path:/tmp/pip-F35ZQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:52:52 INFO - Running setup.py (path:/tmp/pip-K9ynro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:52:52 INFO - Running setup.py (path:/tmp/pip-qRCNxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:52:52 INFO - Running setup.py (path:/tmp/pip-iqZzoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:52:52 INFO - Running setup.py (path:/tmp/pip-WCeM18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:52:52 INFO - Running setup.py (path:/tmp/pip-61u0cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:52: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)) 03:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:52:52 INFO - Running setup.py (path:/tmp/pip-V7nvTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:53 INFO - Running setup.py (path:/tmp/pip-pYCeZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:52:53 INFO - Running setup.py (path:/tmp/pip-iLC5LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:52:53 INFO - Running setup.py (path:/tmp/pip-gq9edh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:52:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:56 INFO - Downloading blessings-1.6.tar.gz 03:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:52:56 INFO - Installing collected packages: blessings 03:52:56 INFO - Running setup.py install for blessings 03:52:56 INFO - Successfully installed blessings 03:52:56 INFO - Cleaning up... 03:52:56 INFO - Return code: 0 03:52:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:56 INFO - Reading from file tmpfile_stdout 03:52:56 INFO - Current package versions: 03:52:56 INFO - argparse == 1.2.1 03:52:56 INFO - blessings == 1.6 03:52:56 INFO - blobuploader == 1.2.4 03:52:56 INFO - docopt == 0.6.1 03:52:56 INFO - functools32 == 3.2.3-2 03:52:56 INFO - jsonschema == 2.5.1 03:52:56 INFO - manifestparser == 1.1 03:52:56 INFO - mozInstall == 1.12 03:52:56 INFO - mozcrash == 0.17 03:52:56 INFO - mozdebug == 0.1 03:52:56 INFO - mozdevice == 0.48 03:52:56 INFO - mozfile == 1.2 03:52:56 INFO - mozhttpd == 0.7 03:52:56 INFO - mozinfo == 0.9 03:52:56 INFO - mozleak == 0.1 03:52:56 INFO - mozlog == 3.3 03:52:56 INFO - moznetwork == 0.27 03:52:56 INFO - mozprocess == 0.23 03:52:56 INFO - mozprofile == 0.28 03:52:56 INFO - mozrunner == 6.12 03:52:56 INFO - mozscreenshot == 0.1 03:52:56 INFO - mozsystemmonitor == 0.3 03:52:56 INFO - moztest == 0.7 03:52:56 INFO - mozversion == 1.4 03:52:56 INFO - psutil == 3.1.1 03:52:56 INFO - requests == 1.2.3 03:52:56 INFO - wsgiref == 0.1.2 03:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:56 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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:52:56 INFO - Using env: {'DISPLAY': ':0', 03:52:56 INFO - 'HOME': '/home/cltbld', 03:52:56 INFO - 'LANG': 'en_US.UTF-8', 03:52:56 INFO - 'LANGUAGE': 'en_US:en', 03:52:56 INFO - 'LOGNAME': 'cltbld', 03:52:56 INFO - 'MAIL': '/var/mail/cltbld', 03:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:56 INFO - 'MOZ_NO_REMOTE': '1', 03:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:56 INFO - 'NO_EM_RESTART': '1', 03:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:56 INFO - 'PWD': '/builds/slave/test', 03:52:56 INFO - 'SHELL': '/bin/bash', 03:52:56 INFO - 'SHLVL': '1', 03:52:56 INFO - 'TERM': 'linux', 03:52:56 INFO - 'TMOUT': '86400', 03:52:56 INFO - 'USER': 'cltbld', 03:52:56 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:56 INFO - '_': '/tools/buildbot/bin/python'} 03:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:52:56 INFO - Cleaning up... 03:52:56 INFO - Return code: 0 03:52:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:52: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')]} 03:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52: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')]} 03:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:56 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 0x17f7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, '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': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:52:56 INFO - Using env: {'DISPLAY': ':0', 03:52:56 INFO - 'HOME': '/home/cltbld', 03:52:56 INFO - 'LANG': 'en_US.UTF-8', 03:52:56 INFO - 'LANGUAGE': 'en_US:en', 03:52:56 INFO - 'LOGNAME': 'cltbld', 03:52:56 INFO - 'MAIL': '/var/mail/cltbld', 03:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:56 INFO - 'MOZ_NO_REMOTE': '1', 03:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:56 INFO - 'NO_EM_RESTART': '1', 03:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:56 INFO - 'PWD': '/builds/slave/test', 03:52:56 INFO - 'SHELL': '/bin/bash', 03:52:56 INFO - 'SHLVL': '1', 03:52:56 INFO - 'TERM': 'linux', 03:52:56 INFO - 'TMOUT': '86400', 03:52:56 INFO - 'USER': 'cltbld', 03:52:56 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:56 INFO - '_': '/tools/buildbot/bin/python'} 03:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:52:57 INFO - Cleaning up... 03:52:57 INFO - Return code: 0 03:52:57 INFO - Running post-action listener: _resource_record_post_action 03:52:57 INFO - Running post-action listener: _start_resource_monitoring 03:52:57 INFO - Starting resource monitoring. 03:52:57 INFO - [mozharness: 2016-08-14 10:52:57.099825Z] Finished create-virtualenv step (success) 03:52:57 INFO - [mozharness: 2016-08-14 10:52:57.100266Z] Running install step. 03:52:57 INFO - Running pre-action listener: _resource_record_pre_action 03:52:57 INFO - Running main action method: install 03:52:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:52:57 INFO - Reading from file tmpfile_stdout 03:52:57 INFO - Detecting whether we're running mozinstall >=1.0... 03:52:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:52:57 INFO - Reading from file tmpfile_stdout 03:52:57 INFO - Output received: 03:52:57 INFO - Usage: mozinstall [options] installer 03:52:57 INFO - Options: 03:52:57 INFO - -h, --help show this help message and exit 03:52:57 INFO - -d DEST, --destination=DEST 03:52:57 INFO - Directory to install application into. [default: 03:52:57 INFO - "/builds/slave/test"] 03:52:57 INFO - --app=APP Application being installed. [default: firefox] 03:52:57 INFO - mkdir: /builds/slave/test/build/application 03:52:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:53:13 INFO - Reading from file tmpfile_stdout 03:53:13 INFO - Output received: 03:53:13 INFO - /builds/slave/test/build/application/firefox/firefox 03:53:13 INFO - Running post-action listener: _resource_record_post_action 03:53:13 INFO - [mozharness: 2016-08-14 10:53:13.917220Z] Finished install step (success) 03:53:13 INFO - [mozharness: 2016-08-14 10:53:13.917497Z] Running run-tests step. 03:53:13 INFO - Running pre-action listener: _resource_record_pre_action 03:53:13 INFO - Running main action method: run_tests 03:53:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:13 INFO - Python 2.7.3 03:53:13 INFO - Return code: 0 03:53:13 INFO - grabbing minidump binary from tooltool 03:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:13 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 0x1ab63b0>, '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 0x1ab72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:53:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:53:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:53:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:13 INFO - Return code: 0 03:53:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:53:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:53:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:13 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/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:53:13 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/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:53:13 INFO - Using env: {'DISPLAY': ':0', 03:53:13 INFO - 'HOME': '/home/cltbld', 03:53:13 INFO - 'LANG': 'en_US.UTF-8', 03:53:13 INFO - 'LANGUAGE': 'en_US:en', 03:53:13 INFO - 'LOGNAME': 'cltbld', 03:53:13 INFO - 'MAIL': '/var/mail/cltbld', 03:53:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:13 INFO - 'MOZ_NO_REMOTE': '1', 03:53:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:13 INFO - 'NO_EM_RESTART': '1', 03:53:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:14 INFO - 'PWD': '/builds/slave/test', 03:53:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:14 INFO - 'SHELL': '/bin/bash', 03:53:14 INFO - 'SHLVL': '1', 03:53:14 INFO - 'TERM': 'linux', 03:53:14 INFO - 'TMOUT': '86400', 03:53:14 INFO - 'USER': 'cltbld', 03:53:14 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655', 03:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:14 INFO - '_': '/tools/buildbot/bin/python'} 03:53:14 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/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:53:14 INFO - mozversion application_buildid: 20160814004012 03:53:14 INFO - mozversion application_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 03:53:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:53:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:14 INFO - mozversion application_name: Firefox 03:53:14 INFO - mozversion application_remotingname: firefox-dev 03:53:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:14 INFO - mozversion application_vendor: Mozilla 03:53:14 INFO - mozversion application_version: 50.0a2 03:53:14 INFO - mozversion platform_buildid: 20160814004012 03:53:14 INFO - mozversion platform_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 03:53:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:14 INFO - mozversion platform_version: 50.0a2 03:53:14 INFO - using testdate: 1471171994 03:53:14 INFO - actual date: 1471171994 03:53:14 INFO - starting webserver on 'localhost:52533' 03:53:14 INFO - SUITE-START | Running 2 tests 03:53:14 INFO - TEST-START | dromaeo_css 03:53:14 INFO - operating with platform_type : linux_ 03:53:14 INFO - Initialising browser for dromaeo_css test... 03:53:14 INFO - TEST-INFO | started process 13233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTG6qzM/profile http://localhost:52533/getInfo.html) 03:53:18 INFO - PROCESS | 13233 | __metrics Screen width/height:1600/1200 03:53:18 INFO - PROCESS | 13233 | colorDepth:24 03:53:18 INFO - PROCESS | 13233 | Browser inner width/height: 1024/697 03:53:18 INFO - PROCESS | 13233 | __metrics 03:53:18 INFO - PROCESS | 13233 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:53:18 INFO - PROCESS | 13233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:53:19 INFO - TEST-INFO | 13233: exit 0 03:53:19 INFO - Browser initialized. 03:53:19 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:19 INFO - TEST-INFO | started process 13321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTG6qzM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:31 INFO - PROCESS | 13321 | RSS: Main: 145891328 03:53:31 INFO - PROCESS | 13321 | 03:54:22 INFO - PROCESS | 13321 | 1471172062464 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:56:31 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52533/tests/dromaeo/cssquery-ext.html) 03:56:32 INFO - PROCESS | 13321 | RSS: Main: 221102080 03:56:32 INFO - PROCESS | 13321 | 03:59:35 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-ext.html (next: http://localhost:52533/tests/dromaeo/cssquery-jquery.html) 03:59:35 INFO - PROCESS | 13321 | RSS: Main: 236433408 03:59:35 INFO - PROCESS | 13321 | 04:02:37 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52533/tests/dromaeo/cssquery-mootools.html) 04:02:38 INFO - PROCESS | 13321 | RSS: Main: 174419968 04:02:38 INFO - PROCESS | 13321 | 04:05:40 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52533/tests/dromaeo/cssquery-prototype.html) 04:05:40 INFO - PROCESS | 13321 | RSS: Main: 175652864 04:05:40 INFO - PROCESS | 13321 | 04:08:43 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52533/tests/dromaeo/cssquery-yui.html) 04:08:43 INFO - PROCESS | 13321 | RSS: Main: 176795648 04:08:43 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | Cycle 1(1): loaded http://localhost:52533/tests/dromaeo/cssquery-yui.html (next: http://localhost:52533/tests/dromaeo/cssquery-dojo.html) 04:11:46 INFO - PROCESS | 13321 | RSS: Main: 264974336 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | __start_tp_report 04:11:46 INFO - PROCESS | 13321 | _x_x_mozilla_page_load 04:11:46 INFO - PROCESS | 13321 | _x_x_mozilla_page_load_details 04:11:46 INFO - PROCESS | 13321 | |i|pagename|runs| 04:11:46 INFO - PROCESS | 13321 | |0;dojo.html;3220.4847224444106;3220.951685724714;3230.4508233600286;3253.560769296147;3274.9836250818757;5563.944360556393;5682.914756278659;5703.144528320754;5710.172025056364;5721.599488035839;5654.434556544344;5666.065099258617;5685.630434021796;5717.742701578429;5721.284839395075;5022.296878437021;5097.617678674096;5118;5151.227315902607;5222.817201397952;5130.102232109384;5176.12005958986;5181.041507321139;5183.352080989876;5207.453217412155;5075.010372977271;5096.235564665311;5121.1038068338075;5145.2796608474655;5202.947970520303;5224.660397074178;5252.1596544552685;5253.658512196695;5257.737113144366;5287.154055351125;4985.277134815431;5020.924686129711;5041.495850414965;5144.151215049513;5147.124988751883;5592.105263157916;5594.720263986785;5604.215409842625;5609.859753506154;5633.8591535211535;6108.297545782203;6119.2350956130485;6120.14317995481;6124.953848303086;6126.479249263818;5868.178455016301;6007.939920600782;6029.065494848305;6061.66660833654;6147;5715.942840571541;5741.024025915604;5758.62568933018;5763.221965034751;5768.798092066758;6275.843103922439;6279.843003924939;6281.371862813773;6286.088517164985;6291.622502649856;5785.508231800344;5886.823395298148;5920.881582368328;5939.495142912814;6031.517478601701;6086.330503644595;6086.904596030979;6093.587590949082;6117.541184411098;6131.417515336036;6035.064564992434;6036.5170786336985;6038.305594856648;6044.546659000593;6060.33336333003;5658.603897727202;5824.534037277008;5988.790392336247;6062.302835174011;6116.3053375860445;4664.370310008106;4696.483386827446;4699.060187962421;4718.4809670936165;4727.976360118246;4174.269502836992;4183.100633363781;4193.203291374629;4203.390508376268;4218.17745539623;5488.17677348401;5643.887122257614;5663.320401551758;5693.259876216148;5710.2862142232225;6089.786857459968;6092.603980741238;6095.603785753912;6110.969445152746;6123.9387606124255;5728.541716662657;5733.455321744427;5737.053386191315;5738.368779434259;5739.512141468022;4912.140375434285;5101.209312556485;5102.1326374515675;5106.259592359087;5188.81839135636;6187.907181392283;6191.690415479208;6194.473469755031;6197.876042479215;6200.131981522591;5942.970285148546;5957.940420595912;5997.130416089555;6001.5798894077;6010.939890601038;6102.481289090477;6131.713108457373;6183.659898705434;6191.84520386994;6289;5976.462118409364;5994.130851026664;5997.640141591519;6002.7298771554515;6038.365971573097;6062.030075187949;6073.726682299395;6076.908846367396;6078.635281883101;6085.543584231112;5735.369109398046;5904.143899134688;5939.554533409925;5952.553558483218;6033.547483938634;5898.675572843535;5944.613600115978;5952.375000624872;5960.612560183574;5972.163897054329;5860.2967643883;5863.853403664772;5925.4370834770625;5925.792597259076;5940.46535811779;5741.110127930177;5947.286325640951;6021.187139736079;6027.668478233739;6082.7262773174425;5919.260092488439;5926.407359264039;5935.4064593542025;5936.673482958478;5937.732802023833 04:11:46 INFO - PROCESS | 13321 | |1;ext.html;606;629.7827249598889;641.2945759664368;651.5406638319985;672.1497305908024;34656.480152797725;47628.761856190715;48627;48679;48766.51233487669;48703.26945095818;48729;49129.75435122829;49176.50823491764;49316.50683493164;8551.230389264965;9335.673251436185;9491.905080949171;9494.905050949506;10898.18263630227;11063.72340691481;11460.426978651076;11480.655580332603;11482.942585287063;11485.425728713573;11534.365609891453;11584.18910676253;11587.304761714282;11600.941995290012;11603.941980290087;11334;11423.828642570326;11450.599229026986;11451.942740286246;11514.942425287903;11094.944525277322;11149.944250278697;11156.274842135235;11158.944205279004;11292;46638;46717.53282467166;47094.529054709354;47106.76446617745;47134.52865471336;3801.334766415866;3914.432407300925;4011.8194681239415;4012.538558065831;4073.124278280154;35660.465093023624;35713.71816773257;35934.46098308527;35961;35964.460533092024;4637.652176086807;4685.273782563708;4713.434387873443;4734.360861283717;4813.95186048142;368084;370387.9999999973;370779;371710.9999999973;371747;62753.372466274755;63303;63346.68326658337;63350.0497492538;63389.36610633927;39691.80154099211;40543.3918491217;40654.593454065674;42151.57848421538;42168.78915605402;19027.714584281246;19287.421377358703;19336.129874155697;19348.903255483634;19352.612947741247;13187.142835715647;14338;14442.638934026734;14578.343974521173;14597.343119559646;21055.263065792624;21150.365489035354;21297.148114075262;21332;21368.572628547343;7348.191698913115;7493.66278517468;7560.924390756132;7581.734639287599;7747.845043099106;1082.924195306321;1098.8351747237978;1140.811766058591;1219.5792451604182;1228.4471987605614;35235.471467928;35261.294774104375;35460;35729.64270357263;35747.4637880432;1131.1120770195357;1140.201858698914;1151.642990672894;1256.6104507602672;1271.8346614939999;18479.53801154982;19443.708344374845;19578.70631940522;19618.411447656592;19709.211631534865;631528;634768;635807;636177;637722;65059.34940650628;65370.34629653642;65429.345706543274;65529.344706553275;65669.67165164239;1364.6656569140623;1457.6501639606417;1467.1930438259128;1467.7504824179914;1516.8938174327911;34485.827570861984;35996.28007439889;36028.4595731059;36125;36144.81927590345;55041;55948.72025639846;56030.719846400505;56081.43918560762;56131.43868561262;481105;484719;486910;487885;488124;30652;31582.36835263328;31589.21026974252;31647;31680;53142.468575315295;53505.19742203792;53577.19634205412;53688.1946770791;53709.73145134406;44612.77693611641;45565.54434455746;45607.77196113998;46359.3046104302;47245;43810.78094609507;44212.55787442085;44833.775831120634;45465;45855.31217031681;44678.10643787041;45300.32049519194;45463.31805022861;45838.541614583424;46023.76988115038;37238.8138059308;38680.61319386883;39836.20327593374;40290.395644064774;40864;29217.707822922348;29405.481670633417;29753.404931901674;29761.5535766968;30255.848720756254 04:11:46 INFO - PROCESS | 13321 | |2;jquery.html;398.4959026831057;410.4643440310394;412.2023883784876;425.6488397072414;441.4283502863791;15025.474108406208;20233.291834785785;20377.694334584994;20471.897640511794;20689.482762930933;19364.12861421236;19556.608867822633;19713.704294435596;19771.802281977176;19882.90058549709;5774.82675519735;5817.243758311424;5916.201312822757;5956.076808094752;5957.88084238315;5889.734961926723;5915.497182739472;5919.763209471616;5946.405359464041;5946.613470124428;5692.544596432296;5849.327327357366;5901.881962360751;5907.6750778707155;5909.852253693671;5744.971275143639;5869.178315035899;5897.764089436418;5911.113333000041;5926.022206335971;5743.310802703661;5829.387914269026;5829.912551311733;5831.620944638585;5835.679037652927;14683.926580367137;18219.817801821944;20504.69242961357;22413.327600172022;22666.09335626573;2412.6260429633435;2486.2044145873147;2492.476579918219;2530.607755797854;2537.936551586207;10781.245312828105;10795.730106747395;10893.564257429693;11079.72300692489;11082.224244302903;2081.2923605974042;2087.1025459052603;2094.4554415851835;2119.3430036688524;2165.4802847316687;57665;62521.70892671058;70635.99999999949;82544.17455825485;85259.14740852636;11247.156463265288;11415.885841141482;11419;11491.080713542897;11817.881821181678;16038;16048.598785030468;16213.189340532927;16216.10811405384;16249.512514624581;8320.500769953822;8437.95781021091;8497.516018432536;8570.657173712982;8703.521306328214;8026.558539280396;8072.959635201786;8276.006879174538;8288.419810613319;8296.792580185424;4885.1206782779445;4977.129002424561;4977.427595826526;5007.599392048627;5149.76826042829;2046.4270004398793;2089.206101681382;2101.317071951622;2125.309274485798;2149.258505815491;904.3036316079442;977.1010670183448;989.1147423056324;1025.026225086171;1046.9057784799252;19342.95087861195;19404.8059519403;19697.409077727403;19960;20230.69653955192;1025.092792878308;1030.7474668706216;1033.9689809305733;1050.2070936442938;1077.488193108283;10308.793824123626;10930.180236482296;10960.043159129531;11003.504842282116;11093.722656933642;557997;560127;576934;586463;587326.1901071487;75462.24537754661;86466;98844.50577747065;99739;100191;976.0873583199731;1013.1793247469574;1035.746242170658;1041.5261056219401;1046.8220402531588;17595.736063959306;17895.463136105936;17923.28306867711;18046.63906721832;18100.09499525045;30939.84530077335;41100.1779964405;44492.55507445014;44557;44735.328970064824;54247.18629220486;63226.68386658037;63482.68258658677;63612.68193659002;65090.02364964435;6349.936500635119;8836.91163088361;8876.51179185151;8932.776680583038;9191.172794448532;11589.188756786943;12939.805902911276;13016.775689492044;13113.409896554487;13228.735425291632;11141.88858111441;11346.319220846775;11437.828432573351;11502.769944601228;11764.117691173025;11049.17131215146;11330.716732081764;11410.201285909907;11562.075033997427;11587.246828956091;11586.246893951866;11665.766684666429;11715.414229288672;11725;11974.521019159136;7644.388448923962;8773.824523509622;8911.465312081296;8987.101289870961;9003.63985440575;10200.846987295048;11429.885701142883;11435.714107147387;11565.826512602149;11712.941435292769 04:11:46 INFO - PROCESS | 13321 | |3;mootools.html;1545.8299587045426;1611.9194040297987;1744.5464179313383;1836.889786612804;1852.5090850924507;9289.44263344199;10040.698779036622;10047.799044019115;10048.748781280463;10049.09558139769;8540.274076703488;9234.353595248336;9487.762805929839;9519.857202141973;9749.805003899935;2456.324510759542;2502.3744063984004;2503.5368456835545;2504.9373765655823;2514.6479492871013;2461.212412028143;2560.9103681371157;2570.3574106473384;2573.472438150184;2575.90984315549;2473.319837044818;2487.315988103268;2487.7139129000125;2489.3029951613576;2494.276659768665;2433.7201221859445;2454.055188752325;2454.2023842251338;2455.9017639294498;2457.741937096597;2360.9645855312183;2413.734489206186;2417.5406672732124;2418.3712234819072;2427.7693619106158;9585.137337639646;9751.073648003428;9751.756206094833;9766.609335626566;9769.267304952085;5642.04085305499;5825.1844741736195;5830.883382332329;5834.4165583441745;5836.241288632493;3315.4032274190636;3412.539307193522;3464.8960531184107;3479.7564170508076;3491.807950562718;1128.2722643894642;1137.6587023892798;1140.526681427215;1173.7945859474557;1175.5297880847643;13916.860831391657;14019.929900350433;14025.719485610332;14054.578362649137;14054.648633784134;4770.141374552544;4782.258749893772;4792.305115758196;4879.804807807719;4995.0509403214;11576;11603.65189044313;11619.6514104577;11621.709457263467;11623.360715160577;5635.549156067505;5644.463775941279;5645.294338207724;5647.802326918538;5651.8304450866535;4593.081383723269;5125.154349532286;5186.688798672092;5239.580833533309;5262.421133675292;4283.721558098714;4375.934360984587;4390.824367025283;4403.647708183337;4483.2826747720865;1329.3884812985941;1362.3937347880099;1365.4538184726189;1366.9179849209079;1366.9931650341885;873.9344549158711;961.8220629183623;1030.8144533983955;1040.6565833275001;1051.5898799468214;6346.9682651586445;6375.458086062737;6451.870962580722;6507.804765857032;6545.905310300658;436.92572262714776;449.6605063177346;454.1711376737467;454.82034596334927;463.8399752085525;4326.740395576276;4346.717463364871;4390.7585082820115;4395.956040439554;4398.956010439919;11792.115591330514;12359.69100772488;12369.072319576067;12378.690532736573;12389.814152787716;11470.426478676032;12043.277403355827;12043.518259269531;12299;12325.445354959049;9047;9196.494192819326;9237.752903358096;9251.259899208047;9281;6642.13652225227;6874.057202225688;6881.243063263138;6942.8611427772175;6955.304469553004;10936.617218397318;11037.503312350804;11057.778844422906;11362.318260904041;11581.594644187413;11326.660200194008;11504;11720.296782193096;11800.881991179978;11869.465874035517;7791.0650721913735;8393.790155246168;8618.741437756877;8663.48019118855;8820.426672266281;9634.084761947604;9718.708438746566;9736.9026309736;9738.659146929824;9740.220782337254;7780.572068536285;8252.09226985019;8323.6670533178;8400.49597024156;8650.394472386872;8372.497650141011;8384.2873355763;8505.106963768716;8521.360897932556;8748.912510875065;7861.449698521049;7882.921170788448;7960.6417711201975;7986.201379861967;7993.400494963017;5662.971685141712;5708.628939118944;5741.3971532988435;5882.735276912635;5967.49276311501;5743.10981797822;5825.854353641193;5896.204012458264;5926.436988486087;5936.732847021981 04:11:46 INFO - PROCESS | 13321 | |4;prototype.html;323.345225917517;338.6308923273632;350.9210427653778;362.09295714235833;363.7817309614232;5568.832935011946;5570.526505247054;5574.581906357019;5587.552995760339;5598.13228949513;5455.399906010336;5489.258950041743;5522.91715624266;5525.557955363572;5545.694986775726;3839.1937693084346;4118.279301122307;4300.741955482681;4304.720193187432;4322.740635561876;4255.297875850496;4298.441202643652;4320.891977700551;4322;4325.545817689144;4281.8287268509375;4297.4099583154275;4309.2889673204045;4310.245707001278;4316.158349121923;4115.8971025724295;4196.979015104936;4255.659547236213;4271.337942618868;4276.2089013532595;3806.333891568964;3812.1232116613332;3818.1363977017236;3824.9426258606145;3834.8466061357512;5325.840224793263;5381.865453363658;5391.379991301012;5392.2181283714035;5393.298871146765;4800.159972004885;4906.803727850882;4922.163232250526;4925.507449255082;4951.232558953368;5730.36965933747;5751.712414379264;5752.626079304831;5758.510526605243;5761.567882408836;4147.170565886835;4214.641755450791;4216.51510076342;4218.599233072853;4224.78876056196;5690.260266165414;5785.971070144665;5834.737436815353;5960.552958528085;5985.5810093293485;4192.664586833107;4222.239996800544;4223.873283801491;4232.386303985905;4247.68142389322;4228.429162063144;4271.5011718144615;4275.978620106879;4287.335463003239;4304.225239456865;4667.533246675373;4853.878653033702;4855.393075865517;4855.514448555116;4863.513648635108;5755.453231942959;5789.913151302734;5791.82624521265;5813.505852002542;5818.679972601463;1490.5081323163547;1499.4901733410693;1505.5483354993678;1506.4802643088335;1507.7512210485365;918.191991047887;953.2326477185793;972.8471760963281;973.9432715503758;974.6539978307741;4949.604031677457;4971.45314015458;4975.577075948585;4981.676191047571;5007.4491805901325;5205.126154584346;5214.869628259248;5216.243644671501;5244.396894357122;5251.632385733038;4993.925091123636;4995.825146119869;5001.42483614389;5271.973640131775;5651.547876169897;3137.733292670103;3166.2401023754132;3168.936621267562;3171.5084161954933;3181.284211052495;6029;6170.629762214281;6176.085413521822;6180.412860778219;6222.004479283293;6001.699915004232;6020.518358531307;6044.821259854374;6065.75736970525;6073.362296958844;6149.692515374213;6150.354212807591;6162.504185804756;6174.765046990709;6192;5616.354119276172;5885.117232415172;5925;5942.584019118794;5948.494377977747;5878.911816322844;5888.823335299948;5910.497607703393;5911.704414779158;5932.762689492372;5834.912476312944;5915.171875937286;5994.520438364833;6042.607230530001;6176.413240742122;4187.371894215892;4244.617984381421;4279.122779830065;4302.849400270976;4307.135754723074;5855.385184555731;5916.201312822714;5969.403059694169;5971.253593300838;5972.641641501524;5616.550675945833;5848.912266315923;5850.502707269759;5856.9121463178935;5874.151036747089;5823.534117270693;5843.2403787507355;5852.297724273115;5859.267591551056;5864.530837533072;5775.711214439345;5776.335721392094;5799.449052340021;5847.298324201123;5850.444207800252;5697.313486121192;5859.267591551056;5888.823335299948;5892.499137573182;5899.734511947058;5719.341390996565;5734.942650573607;5743.984408185736;5745.3105627324985;5752.28096487939 04:11:46 INFO - PROCESS | 13321 | |5;yui.html;796.1520980156134;836.122071824584;844.5903736687706;857.4212406625522;904.7666237241102;16430.671386572263;17356.04541750203;17534.473965781017;17541.73687394687;17660;14898.627534311647;15222.467213647524;15291.158986255752;15292.617684557892;16138.596535086628;735.2977906099688;875.9036505984336;887.9556022198864;888.1873086126187;889.3818795936827;861.9224269815716;897.3808072430035;900.4957223954574;901.9909800902005;902.7517432706026;950.4677380666849;952.8761261036055;953.8998405167462;954.5131982688844;954.5656726189567;763.7594157840263;935.4621092871625;936;936.2556767369957;936.3258453913205;665.6272487407019;683.9076724642159;686.4508393285402;689.007828726632;689.6034780001469;15967.121808300539;16002.279897404645;16032.198390080566;16032.919835400866;16050.277737501841;15146;15391.153486558234;15806.762898556532;15902.88679792415;16028.519144425685;13656.317184140753;13834.515791947233;13951.720965580733;14079.929600352036;14147.787783183261;4649.349091127245;4692.2726977318225;4703.858884233478;4753.881152971203;4758.048390321915;86225.56887215587;86315.70526442108;86741;87338;93733.06266937312;11708.356040417688;11721.472533736003;11813.232139910879;11878.168528203116;12052.33712145823;21521.89239053795;21634.783652163278;21654.35036948894;21757.12971481123;21785.564288714137;9165.358424910326;9209.63161473547;9311.161995420445;9336.95331523338;9353.345265831327;5107.540321371094;5279.128943724242;5279.551238144801;5336.626436149432;5532.474414930575;3685.502457168248;3807.0672685192303;3811.0281878121455;3821.464994900716;3830.1190726132586;675.0448115916022;677.8847595908708;684.3806355248508;686.3171144711044;715.6815217228284;703.2931903437013;725.4812808841598;727.3381223086966;729.1978823294439;757.9469437139347;9883.851742223873;10280.485975701185;10336.638217662347;10399.428031458343;10514.158867290598;634.2199095113036;642.8682120165355;653.5000724445803;686.3548264631231;717.7093277222733;4924.60603151747;4964.230544265644;4969.701817890938;5004.099262132851;5058.266551350033;126467.94128234767;127061;129687;134720;134777;109790;112510;113684;114077.42961285141;114495;95176.52411737897;98522.50738746261;101103.4944825286;102738;103694;9513.286503512403;9627.374220675634;9939.105480506789;9979;9991.750206244902;57099.28702138943;59614.70192649009;60300;61345.07982380179;62761.05858412219;81250.18749812426;82427;84272.57863710642;89298;92036.53981730048;12679.556215532411;12777.84999350063;12796.744065118832;13272.336383180995;13898.09662371983;13699.726005479635;15026.849731502545;15123.243837808286;15991.840081599501;16030.519084427486;14586.124832510084;14779.408823646934;15130.697386051997;15257.237138143271;15996.68006639884;14760.33578488949;14827.777583336043;15210.847891521385;15260.522532348597;15550.61123471877;14132.929335353258;14626.926865366031;14659.633509162357;14975.70048599;15406.229688515305;11647;12209.206401583868;12224.144309898222;12224.572139975058;12551.811722824348;12512.998960083354;12609.495620175458;12875.163114397534;12879.613611591667;13030.47878084866 04:11:46 INFO - PROCESS | 13321 | __end_tp_report 04:11:46 INFO - PROCESS | 13321 | __start_cc_report 04:11:46 INFO - PROCESS | 13321 | _x_x_mozilla_cycle_collect,1046 04:11:46 INFO - PROCESS | 13321 | __end_cc_report 04:11:46 INFO - PROCESS | 13321 | __startTimestamp1471173106522__endTimestamp 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | ------- Summary: start ------- 04:11:46 INFO - PROCESS | 13321 | Number of tests: 6 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#0] dojo.html Cycles:165 Average:5626.41 Median:5868.18 stddev:638.98 (10.9%) stddev-sans-first:612.43 04:11:46 INFO - PROCESS | 13321 | Values: 3220.5 3221.0 3230.5 3253.6 3275.0 5563.9 5682.9 5703.1 5710.2 5721.6 5654.4 5666.1 5685.6 5717.7 5721.3 5022.3 5097.6 5118.0 5151.2 5222.8 5130.1 5176.1 5181.0 5183.4 5207.5 5075.0 5096.2 5121.1 5145.3 5202.9 5224.7 5252.2 5253.7 5257.7 5287.2 4985.3 5020.9 5041.5 5144.2 5147.1 5592.1 5594.7 5604.2 5609.9 5633.9 6108.3 6119.2 6120.1 6125.0 6126.5 5868.2 6007.9 6029.1 6061.7 6147.0 5715.9 5741.0 5758.6 5763.2 5768.8 6275.8 6279.8 6281.4 6286.1 6291.6 5785.5 5886.8 5920.9 5939.5 6031.5 6086.3 6086.9 6093.6 6117.5 6131.4 6035.1 6036.5 6038.3 6044.5 6060.3 5658.6 5824.5 5988.8 6062.3 6116.3 4664.4 4696.5 4699.1 4718.5 4728.0 4174.3 4183.1 4193.2 4203.4 4218.2 5488.2 5643.9 5663.3 5693.3 5710.3 6089.8 6092.6 6095.6 6111.0 6123.9 5728.5 5733.5 5737.1 5738.4 5739.5 4912.1 5101.2 5102.1 5106.3 5188.8 6187.9 6191.7 6194.5 6197.9 6200.1 5943.0 5957.9 5997.1 6001.6 6010.9 6102.5 6131.7 6183.7 6191.8 6289.0 5976.5 5994.1 5997.6 6002.7 6038.4 6062.0 6073.7 6076.9 6078.6 6085.5 5735.4 5904.1 5939.6 5952.6 6033.5 5898.7 5944.6 5952.4 5960.6 5972.2 5860.3 5863.9 5925.4 5925.8 5940.5 5741.1 5947.3 6021.2 6027.7 6082.7 5919.3 5926.4 5935.4 5936.7 5937.7 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#1] ext.html Cycles:180 Average:66828.55 Median:21803.12 stddev:135177.87 (620.0%) stddev-sans-first:135465.63 04:11:46 INFO - PROCESS | 13321 | Values: 606.0 629.8 641.3 651.5 672.1 34656.5 47628.8 48627.0 48679.0 48766.5 48703.3 48729.0 49129.8 49176.5 49316.5 8551.2 9335.7 9491.9 9494.9 10898.2 11063.7 11460.4 11480.7 11482.9 11485.4 11534.4 11584.2 11587.3 11600.9 11603.9 11334.0 11423.8 11450.6 11451.9 11514.9 11094.9 11149.9 11156.3 11158.9 11292.0 46638.0 46717.5 47094.5 47106.8 47134.5 3801.3 3914.4 4011.8 4012.5 4073.1 35660.5 35713.7 35934.5 35961.0 35964.5 4637.7 4685.3 4713.4 4734.4 4814.0 368084.0 370388.0 370779.0 371711.0 371747.0 62753.4 63303.0 63346.7 63350.0 63389.4 39691.8 40543.4 40654.6 42151.6 42168.8 19027.7 19287.4 19336.1 19348.9 19352.6 13187.1 14338.0 14442.6 14578.3 14597.3 21055.3 21150.4 21297.1 21332.0 21368.6 7348.2 7493.7 7560.9 7581.7 7747.8 1082.9 1098.8 1140.8 1219.6 1228.4 35235.5 35261.3 35460.0 35729.6 35747.5 1131.1 1140.2 1151.6 1256.6 1271.8 18479.5 19443.7 19578.7 19618.4 19709.2 631528.0 634768.0 635807.0 636177.0 637722.0 65059.3 65370.3 65429.3 65529.3 65669.7 1364.7 1457.7 1467.2 1467.8 1516.9 34485.8 35996.3 36028.5 36125.0 36144.8 55041.0 55948.7 56030.7 56081.4 56131.4 481105.0 484719.0 486910.0 487885.0 488124.0 30652.0 31582.4 31589.2 31647.0 31680.0 53142.5 53505.2 53577.2 53688.2 53709.7 44612.8 45565.5 45607.8 46359.3 47245.0 43810.8 44212.6 44833.8 45465.0 45855.3 44678.1 45300.3 45463.3 45838.5 46023.8 37238.8 38680.6 39836.2 40290.4 40864.0 29217.7 29405.5 29753.4 29761.6 30255.8 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#2] jquery.html Cycles:180 Average:31206.37 Median:22539.71 stddev:94162.91 (417.8%) stddev-sans-first:94398.65 04:11:46 INFO - PROCESS | 13321 | Values: 398.5 410.5 412.2 425.6 441.4 15025.5 20233.3 20377.7 20471.9 20689.5 19364.1 19556.6 19713.7 19771.8 19882.9 5774.8 5817.2 5916.2 5956.1 5957.9 5889.7 5915.5 5919.8 5946.4 5946.6 5692.5 5849.3 5901.9 5907.7 5909.9 5745.0 5869.2 5897.8 5911.1 5926.0 5743.3 5829.4 5829.9 5831.6 5835.7 14683.9 18219.8 20504.7 22413.3 22666.1 2412.6 2486.2 2492.5 2530.6 2537.9 10781.2 10795.7 10893.6 11079.7 11082.2 2081.3 2087.1 2094.5 2119.3 2165.5 57665.0 62521.7 70636.0 82544.2 85259.1 11247.2 11415.9 11419.0 11491.1 11817.9 16038.0 16048.6 16213.2 16216.1 16249.5 8320.5 8438.0 8497.5 8570.7 8703.5 8026.6 8073.0 8276.0 8288.4 8296.8 4885.1 4977.1 4977.4 5007.6 5149.8 2046.4 2089.2 2101.3 2125.3 2149.3 904.3 977.1 989.1 1025.0 1046.9 19343.0 19404.8 19697.4 19960.0 20230.7 1025.1 1030.7 1034.0 1050.2 1077.5 10308.8 10930.2 10960.0 11003.5 11093.7 557997.0 560127.0 576934.0 586463.0 587326.2 75462.2 86466.0 98844.5 99739.0 100191.0 976.1 1013.2 1035.7 1041.5 1046.8 17595.7 17895.5 17923.3 18046.6 18100.1 30939.8 41100.2 44492.6 44557.0 44735.3 54247.2 63226.7 63482.7 63612.7 65090.0 6349.9 8836.9 8876.5 8932.8 9191.2 11589.2 12939.8 13016.8 13113.4 13228.7 11141.9 11346.3 11437.8 11502.8 11764.1 11049.2 11330.7 11410.2 11562.1 11587.2 11586.2 11665.8 11715.4 11725.0 11974.5 7644.4 8773.8 8911.5 8987.1 9003.6 10200.8 11429.9 11435.7 11565.8 11712.9 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#3] mootools.html Cycles:180 Average:6398.96 Median:4401.30 stddev:3763.58 (85.5%) stddev-sans-first:3756.47 04:11:46 INFO - PROCESS | 13321 | Values: 1545.8 1611.9 1744.5 1836.9 1852.5 9289.4 10040.7 10047.8 10048.7 10049.1 8540.3 9234.4 9487.8 9519.9 9749.8 2456.3 2502.4 2503.5 2504.9 2514.6 2461.2 2560.9 2570.4 2573.5 2575.9 2473.3 2487.3 2487.7 2489.3 2494.3 2433.7 2454.1 2454.2 2455.9 2457.7 2361.0 2413.7 2417.5 2418.4 2427.8 9585.1 9751.1 9751.8 9766.6 9769.3 5642.0 5825.2 5830.9 5834.4 5836.2 3315.4 3412.5 3464.9 3479.8 3491.8 1128.3 1137.7 1140.5 1173.8 1175.5 13916.9 14019.9 14025.7 14054.6 14054.6 4770.1 4782.3 4792.3 4879.8 4995.1 11576.0 11603.7 11619.7 11621.7 11623.4 5635.5 5644.5 5645.3 5647.8 5651.8 4593.1 5125.2 5186.7 5239.6 5262.4 4283.7 4375.9 4390.8 4403.6 4483.3 1329.4 1362.4 1365.5 1366.9 1367.0 873.9 961.8 1030.8 1040.7 1051.6 6347.0 6375.5 6451.9 6507.8 6545.9 436.9 449.7 454.2 454.8 463.8 4326.7 4346.7 4390.8 4396.0 4399.0 11792.1 12359.7 12369.1 12378.7 12389.8 11470.4 12043.3 12043.5 12299.0 12325.4 9047.0 9196.5 9237.8 9251.3 9281.0 6642.1 6874.1 6881.2 6942.9 6955.3 10936.6 11037.5 11057.8 11362.3 11581.6 11326.7 11504.0 11720.3 11800.9 11869.5 7791.1 8393.8 8618.7 8663.5 8820.4 9634.1 9718.7 9736.9 9738.7 9740.2 7780.6 8252.1 8323.7 8400.5 8650.4 8372.5 8384.3 8505.1 8521.4 8748.9 7861.4 7882.9 7960.6 7986.2 7993.4 5663.0 5708.6 5741.4 5882.7 5967.5 5743.1 5825.9 5896.2 5926.4 5936.7 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#4] prototype.html Cycles:180 Average:4829.14 Median:5506.09 stddev:1417.66 (25.7%) stddev-sans-first:1380.71 04:11:46 INFO - PROCESS | 13321 | Values: 323.3 338.6 350.9 362.1 363.8 5568.8 5570.5 5574.6 5587.6 5598.1 5455.4 5489.3 5522.9 5525.6 5545.7 3839.2 4118.3 4300.7 4304.7 4322.7 4255.3 4298.4 4320.9 4322.0 4325.5 4281.8 4297.4 4309.3 4310.2 4316.2 4115.9 4197.0 4255.7 4271.3 4276.2 3806.3 3812.1 3818.1 3824.9 3834.8 5325.8 5381.9 5391.4 5392.2 5393.3 4800.2 4906.8 4922.2 4925.5 4951.2 5730.4 5751.7 5752.6 5758.5 5761.6 4147.2 4214.6 4216.5 4218.6 4224.8 5690.3 5786.0 5834.7 5960.6 5985.6 4192.7 4222.2 4223.9 4232.4 4247.7 4228.4 4271.5 4276.0 4287.3 4304.2 4667.5 4853.9 4855.4 4855.5 4863.5 5755.5 5789.9 5791.8 5813.5 5818.7 1490.5 1499.5 1505.5 1506.5 1507.8 918.2 953.2 972.8 973.9 974.7 4949.6 4971.5 4975.6 4981.7 5007.4 5205.1 5214.9 5216.2 5244.4 5251.6 4993.9 4995.8 5001.4 5272.0 5651.5 3137.7 3166.2 3168.9 3171.5 3181.3 6029.0 6170.6 6176.1 6180.4 6222.0 6001.7 6020.5 6044.8 6065.8 6073.4 6149.7 6150.4 6162.5 6174.8 6192.0 5616.4 5885.1 5925.0 5942.6 5948.5 5878.9 5888.8 5910.5 5911.7 5932.8 5834.9 5915.2 5994.5 6042.6 6176.4 4187.4 4244.6 4279.1 4302.8 4307.1 5855.4 5916.2 5969.4 5971.3 5972.6 5616.6 5848.9 5850.5 5856.9 5874.2 5823.5 5843.2 5852.3 5859.3 5864.5 5775.7 5776.3 5799.4 5847.3 5850.4 5697.3 5859.3 5888.8 5892.5 5899.7 5719.3 5734.9 5744.0 5745.3 5752.3 04:11:46 INFO - PROCESS | 13321 | 04:11:46 INFO - PROCESS | 13321 | [#5] yui.html Cycles:180 Average:23423.92 Median:3825.79 stddev:34459.96 (900.7%) stddev-sans-first:34514.74 04:11:46 INFO - PROCESS | 13321 | Values: 796.2 836.1 844.6 857.4 904.8 16430.7 17356.0 17534.5 17541.7 17660.0 14898.6 15222.5 15291.2 15292.6 16138.6 735.3 875.9 888.0 888.2 889.4 861.9 897.4 900.5 902.0 902.8 950.5 952.9 953.9 954.5 954.6 763.8 935.5 936.0 936.3 936.3 665.6 683.9 686.5 689.0 689.6 15967.1 16002.3 16032.2 16032.9 16050.3 15146.0 15391.2 15806.8 15902.9 16028.5 13656.3 13834.5 13951.7 14079.9 14147.8 4649.3 4692.3 4703.9 4753.9 4758.0 86225.6 86315.7 86741.0 87338.0 93733.1 11708.4 11721.5 11813.2 11878.2 12052.3 21521.9 21634.8 21654.4 21757.1 21785.6 9165.4 9209.6 9311.2 9337.0 9353.3 5107.5 5279.1 5279.6 5336.6 5532.5 3685.5 3807.1 3811.0 3821.5 3830.1 675.0 677.9 684.4 686.3 715.7 703.3 725.5 727.3 729.2 757.9 9883.9 10280.5 10336.6 10399.4 10514.2 634.2 642.9 653.5 686.4 717.7 4924.6 4964.2 4969.7 5004.1 5058.3 126467.9 127061.0 129687.0 134720.0 134777.0 109790.0 112510.0 113684.0 114077.4 114495.0 95176.5 98522.5 101103.5 102738.0 103694.0 9513.3 9627.4 9939.1 9979.0 9991.8 57099.3 59614.7 60300.0 61345.1 62761.1 81250.2 82427.0 84272.6 89298.0 92036.5 12679.6 12777.8 12796.7 13272.3 13898.1 13699.7 15026.8 15123.2 15991.8 16030.5 14586.1 14779.4 15130.7 15257.2 15996.7 14760.3 14827.8 15210.8 15260.5 15550.6 14132.9 14626.9 14659.6 14975.7 15406.2 11647.0 12209.2 12224.1 12224.6 12551.8 12513.0 12609.5 12875.2 12879.6 13030.5 04:11:46 INFO - PROCESS | 13321 | -------- Summary: end -------- 04:11:46 INFO - PROCESS | 13321 | 04:11:47 INFO - TEST-INFO | 13321: exit 0 04:11:47 INFO - TEST-OK | dromaeo_css | took 1113087ms 04:11:47 INFO - TEST-START | kraken 04:11:47 INFO - operating with platform_type : linux_ 04:11:47 INFO - Initialising browser for kraken test... 04:11:47 INFO - TEST-INFO | started process 13524 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDLusIa/profile http://localhost:52533/getInfo.html) 04:11:51 INFO - PROCESS | 13524 | __metrics Screen width/height:1600/1200 04:11:51 INFO - PROCESS | 13524 | colorDepth:24 04:11:51 INFO - PROCESS | 13524 | Browser inner width/height: 1024/697 04:11:51 INFO - PROCESS | 13524 | __metrics 04:11:51 INFO - PROCESS | 13524 | 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:11:51 INFO - PROCESS | 13524 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:11:52 INFO - TEST-INFO | 13524: exit 0 04:11:52 INFO - Browser initialized. 04:11:52 INFO - Running cycle 1/1 for kraken test... 04:11:52 INFO - TEST-INFO | started process 13614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDLusIa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:04 INFO - PROCESS | 13614 | RSS: Main: 145481728 04:12:04 INFO - PROCESS | 13614 | 04:12:07 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52533/tests/kraken/driver.html?testName=audio-beat-detection) 04:12:07 INFO - PROCESS | 13614 | RSS: Main: 191336448 04:12:07 INFO - PROCESS | 13614 | 04:12:10 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52533/tests/kraken/driver.html?testName=audio-dft) 04:12:11 INFO - PROCESS | 13614 | RSS: Main: 181280768 04:12:11 INFO - PROCESS | 13614 | 04:12:13 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52533/tests/kraken/driver.html?testName=audio-fft) 04:12:14 INFO - PROCESS | 13614 | RSS: Main: 270528512 04:12:14 INFO - PROCESS | 13614 | 04:12:16 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52533/tests/kraken/driver.html?testName=audio-oscillator) 04:12:17 INFO - PROCESS | 13614 | RSS: Main: 174919680 04:12:17 INFO - PROCESS | 13614 | 04:12:19 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52533/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:19 INFO - PROCESS | 13614 | RSS: Main: 176668672 04:12:19 INFO - PROCESS | 13614 | 04:12:22 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52533/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:23 INFO - PROCESS | 13614 | RSS: Main: 213069824 04:12:23 INFO - PROCESS | 13614 | 04:12:26 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52533/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:27 INFO - PROCESS | 13614 | RSS: Main: 212066304 04:12:27 INFO - PROCESS | 13614 | 04:12:30 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52533/tests/kraken/driver.html?testName=json-parse-financial) 04:12:30 INFO - PROCESS | 13614 | RSS: Main: 211415040 04:12:30 INFO - PROCESS | 13614 | 04:12:32 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52533/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:32 INFO - PROCESS | 13614 | RSS: Main: 175460352 04:12:32 INFO - PROCESS | 13614 | 04:12:34 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:35 INFO - PROCESS | 13614 | RSS: Main: 182558720 04:12:35 INFO - PROCESS | 13614 | 04:12:37 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:37 INFO - PROCESS | 13614 | RSS: Main: 185708544 04:12:37 INFO - PROCESS | 13614 | 04:12:40 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:12:40 INFO - PROCESS | 13614 | RSS: Main: 181387264 04:12:40 INFO - PROCESS | 13614 | 04:12:43 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:12:44 INFO - PROCESS | 13614 | RSS: Main: 184291328 04:12:44 INFO - PROCESS | 13614 | 04:12:45 INFO - PROCESS | 13614 | Cycle 1(1): loaded http://localhost:52533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52533/tests/kraken/driver.html?testName=ai-astar) 04:12:46 INFO - PROCESS | 13614 | RSS: Main: 234971136 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | __start_tp_report 04:12:46 INFO - PROCESS | 13614 | _x_x_mozilla_page_load 04:12:46 INFO - PROCESS | 13614 | _x_x_mozilla_page_load_details 04:12:46 INFO - PROCESS | 13614 | |i|pagename|runs| 04:12:46 INFO - PROCESS | 13614 | |0;ai-astar;101;110;110;110;110;110;109;108;110;111 04:12:46 INFO - PROCESS | 13614 | |1;audio-beat-detection;147;115;121;177;116;118;142;120;164;121 04:12:46 INFO - PROCESS | 13614 | |2;audio-dft;162;162;161;162;160;161;161;160;162;159 04:12:46 INFO - PROCESS | 13614 | |3;audio-fft;73;76;74;114;75;76;74;75;108;74 04:12:46 INFO - PROCESS | 13614 | |4;audio-oscillator;83;84;141;84;85;83;120;84;84;124 04:12:46 INFO - PROCESS | 13614 | |5;imaging-gaussian-blur;116;118;117;117;117;117;117;117;117;117 04:12:46 INFO - PROCESS | 13614 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;145;145 04:12:46 INFO - PROCESS | 13614 | |7;imaging-desaturate;96;95;95;96;95;95;95;94;95;95 04:12:46 INFO - PROCESS | 13614 | |8;json-parse-financial;52;51;50;50;51;50;50;51;50;50 04:12:46 INFO - PROCESS | 13614 | |9;json-stringify-tinderbox;59;60;60;60;60;59;60;60;60;60 04:12:46 INFO - PROCESS | 13614 | |10;stanford-crypto-aes;72;72;72;71;72;71;72;72;77;73 04:12:46 INFO - PROCESS | 13614 | |11;stanford-crypto-ccm;121;117;117;117;115;116;125;118;159;119 04:12:46 INFO - PROCESS | 13614 | |12;stanford-crypto-pbkdf2;149;149;149;153;150;153;151;148;148;157 04:12:46 INFO - PROCESS | 13614 | |13;stanford-crypto-sha256-iterative;62;70;61;59;60;67;64;60;61;60 04:12:46 INFO - PROCESS | 13614 | __end_tp_report 04:12:46 INFO - PROCESS | 13614 | __start_cc_report 04:12:46 INFO - PROCESS | 13614 | _x_x_mozilla_cycle_collect,1592 04:12:46 INFO - PROCESS | 13614 | __end_cc_report 04:12:46 INFO - PROCESS | 13614 | __startTimestamp1471173166033__endTimestamp 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | ------- Summary: start ------- 04:12:46 INFO - PROCESS | 13614 | Number of tests: 14 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.88 (2.6%) stddev-sans-first:0.83 04:12:46 INFO - PROCESS | 13614 | Values: 101.0 110.0 110.0 110.0 110.0 110.0 109.0 108.0 110.0 111.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:131.50 stddev:22.25 (16.9%) stddev-sans-first:23.11 04:12:46 INFO - PROCESS | 13614 | Values: 147.0 115.0 121.0 177.0 116.0 118.0 142.0 120.0 164.0 121.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.05 (0.7%) stddev-sans-first:1.05 04:12:46 INFO - PROCESS | 13614 | Values: 162.0 162.0 161.0 162.0 160.0 161.0 161.0 160.0 162.0 159.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#3] audio-fft Cycles:10 Average:81.90 Median:74.50 stddev:15.43 (20.7%) stddev-sans-first:16.03 04:12:46 INFO - PROCESS | 13614 | Values: 73.0 76.0 74.0 114.0 75.0 76.0 74.0 75.0 108.0 74.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:84.00 stddev:22.12 (26.3%) stddev-sans-first:22.86 04:12:46 INFO - PROCESS | 13614 | Values: 83.0 84.0 141.0 84.0 85.0 83.0 120.0 84.0 84.0 124.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 04:12:46 INFO - PROCESS | 13614 | Values: 116.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 04:12:46 INFO - PROCESS | 13614 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 04:12:46 INFO - PROCESS | 13614 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:0.71 (1.4%) stddev-sans-first:0.50 04:12:46 INFO - PROCESS | 13614 | Values: 52.0 51.0 50.0 50.0 51.0 50.0 50.0 51.0 50.0 50.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 04:12:46 INFO - PROCESS | 13614 | Values: 59.0 60.0 60.0 60.0 60.0 59.0 60.0 60.0 60.0 60.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.00 stddev:1.71 (2.4%) stddev-sans-first:1.81 04:12:46 INFO - PROCESS | 13614 | Values: 72.0 72.0 72.0 71.0 72.0 71.0 72.0 72.0 77.0 73.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#11] stanford-crypto-ccm Cycles:10 Average:122.40 Median:118.50 stddev:13.18 (11.1%) stddev-sans-first:13.97 04:12:46 INFO - PROCESS | 13614 | Values: 121.0 117.0 117.0 117.0 115.0 116.0 125.0 118.0 159.0 119.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:150.50 stddev:2.87 (1.9%) stddev-sans-first:2.98 04:12:46 INFO - PROCESS | 13614 | Values: 149.0 149.0 149.0 153.0 150.0 153.0 151.0 148.0 148.0 157.0 04:12:46 INFO - PROCESS | 13614 | 04:12:46 INFO - PROCESS | 13614 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:61.50 stddev:3.57 (5.8%) stddev-sans-first:3.78 04:12:46 INFO - PROCESS | 13614 | Values: 62.0 70.0 61.0 59.0 60.0 67.0 64.0 60.0 61.0 60.0 04:12:46 INFO - PROCESS | 13614 | -------- Summary: end -------- 04:12:46 INFO - PROCESS | 13614 | 04:12:47 INFO - TEST-INFO | 13614: exit 0 04:12:47 INFO - TEST-OK | kraken | took 59917ms 04:12:47 INFO - SUITE-END | took 1173s 04:12:47 INFO - Completed test suite (00:19:33) 04:12:47 INFO - javascript benchmark 04:12:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3220.4847224444106, 3220.951685724714, 3230.4508233600286, 3253.560769296147, 3274.9836250818757, 5563.944360556393, 5682.914756278659, 5703.144528320754, 5710.172025056364, 5721.599488035839, 5654.434556544344, 5666.065099258617, 5685.630434021796, 5717.742701578429, 5721.284839395075, 5022.296878437021, 5097.617678674096, 5118.0, 5151.227315902607, 5222.817201397952, 5130.102232109384, 5176.12005958986, 5181.041507321139, 5183.352080989876, 5207.453217412155, 5075.010372977271, 5096.235564665311, 5121.1038068338075, 5145.2796608474655, 5202.947970520303, 5224.660397074178, 5252.1596544552685, 5253.658512196695, 5257.737113144366, 5287.154055351125, 4985.277134815431, 5020.924686129711, 5041.495850414965, 5144.151215049513, 5147.124988751883, 5592.105263157916, 5594.720263986785, 5604.215409842625, 5609.859753506154, 5633.8591535211535, 6108.297545782203, 6119.2350956130485, 6120.14317995481, 6124.953848303086, 6126.479249263818, 5868.178455016301, 6007.939920600782, 6029.065494848305, 6061.66660833654, 6147.0, 5715.942840571541, 5741.024025915604, 5758.62568933018, 5763.221965034751, 5768.798092066758, 6275.843103922439, 6279.843003924939, 6281.371862813773, 6286.088517164985, 6291.622502649856, 5785.508231800344, 5886.823395298148, 5920.881582368328, 5939.495142912814, 6031.517478601701, 6086.330503644595, 6086.904596030979, 6093.587590949082, 6117.541184411098, 6131.417515336036, 6035.064564992434, 6036.5170786336985, 6038.305594856648, 6044.546659000593, 6060.33336333003, 5658.603897727202, 5824.534037277008, 5988.790392336247, 6062.302835174011, 6116.3053375860445, 4664.370310008106, 4696.483386827446, 4699.060187962421, 4718.4809670936165, 4727.976360118246, 4174.269502836992, 4183.100633363781, 4193.203291374629, 4203.390508376268, 4218.17745539623, 5488.17677348401, 5643.887122257614, 5663.320401551758, 5693.259876216148, 5710.2862142232225, 6089.786857459968, 6092.603980741238, 6095.603785753912, 6110.969445152746, 6123.9387606124255, 5728.541716662657, 5733.455321744427, 5737.053386191315, 5738.368779434259, 5739.512141468022, 4912.140375434285, 5101.209312556485, 5102.1326374515675, 5106.259592359087, 5188.81839135636, 6187.907181392283, 6191.690415479208, 6194.473469755031, 6197.876042479215, 6200.131981522591, 5942.970285148546, 5957.940420595912, 5997.130416089555, 6001.5798894077, 6010.939890601038, 6102.481289090477, 6131.713108457373, 6183.659898705434, 6191.84520386994, 6289.0, 5976.462118409364, 5994.130851026664, 5997.640141591519, 6002.7298771554515, 6038.365971573097, 6062.030075187949, 6073.726682299395, 6076.908846367396, 6078.635281883101, 6085.543584231112, 5735.369109398046, 5904.143899134688, 5939.554533409925, 5952.553558483218, 6033.547483938634, 5898.675572843535, 5944.613600115978, 5952.375000624872, 5960.612560183574, 5972.163897054329, 5860.2967643883, 5863.853403664772, 5925.4370834770625, 5925.792597259076, 5940.46535811779, 5741.110127930177, 5947.286325640951, 6021.187139736079, 6027.668478233739, 6082.7262773174425, 5919.260092488439, 5926.407359264039, 5935.4064593542025, 5936.673482958478, 5937.732802023833], "value": 5583.114359234707, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.0, 629.7827249598889, 641.2945759664368, 651.5406638319985, 672.1497305908024, 34656.480152797725, 47628.761856190715, 48627.0, 48679.0, 48766.51233487669, 48703.26945095818, 48729.0, 49129.75435122829, 49176.50823491764, 49316.50683493164, 8551.230389264965, 9335.673251436185, 9491.905080949171, 9494.905050949506, 10898.18263630227, 11063.72340691481, 11460.426978651076, 11480.655580332603, 11482.942585287063, 11485.425728713573, 11534.365609891453, 11584.18910676253, 11587.304761714282, 11600.941995290012, 11603.941980290087, 11334.0, 11423.828642570326, 11450.599229026986, 11451.942740286246, 11514.942425287903, 11094.944525277322, 11149.944250278697, 11156.274842135235, 11158.944205279004, 11292.0, 46638.0, 46717.53282467166, 47094.529054709354, 47106.76446617745, 47134.52865471336, 3801.334766415866, 3914.432407300925, 4011.8194681239415, 4012.538558065831, 4073.124278280154, 35660.465093023624, 35713.71816773257, 35934.46098308527, 35961.0, 35964.460533092024, 4637.652176086807, 4685.273782563708, 4713.434387873443, 4734.360861283717, 4813.95186048142, 368084.0, 370387.9999999973, 370779.0, 371710.9999999973, 371747.0, 62753.372466274755, 63303.0, 63346.68326658337, 63350.0497492538, 63389.36610633927, 39691.80154099211, 40543.3918491217, 40654.593454065674, 42151.57848421538, 42168.78915605402, 19027.714584281246, 19287.421377358703, 19336.129874155697, 19348.903255483634, 19352.612947741247, 13187.142835715647, 14338.0, 14442.638934026734, 14578.343974521173, 14597.343119559646, 21055.263065792624, 21150.365489035354, 21297.148114075262, 21332.0, 21368.572628547343, 7348.191698913115, 7493.66278517468, 7560.924390756132, 7581.734639287599, 7747.845043099106, 1082.924195306321, 1098.8351747237978, 1140.811766058591, 1219.5792451604182, 1228.4471987605614, 35235.471467928, 35261.294774104375, 35460.0, 35729.64270357263, 35747.4637880432, 1131.1120770195357, 1140.201858698914, 1151.642990672894, 1256.6104507602672, 1271.8346614939999, 18479.53801154982, 19443.708344374845, 19578.70631940522, 19618.411447656592, 19709.211631534865, 631528.0, 634768.0, 635807.0, 636177.0, 637722.0, 65059.34940650628, 65370.34629653642, 65429.345706543274, 65529.344706553275, 65669.67165164239, 1364.6656569140623, 1457.6501639606417, 1467.1930438259128, 1467.7504824179914, 1516.8938174327911, 34485.827570861984, 35996.28007439889, 36028.4595731059, 36125.0, 36144.81927590345, 55041.0, 55948.72025639846, 56030.719846400505, 56081.43918560762, 56131.43868561262, 481105.0, 484719.0, 486910.0, 487885.0, 488124.0, 30652.0, 31582.36835263328, 31589.21026974252, 31647.0, 31680.0, 53142.468575315295, 53505.19742203792, 53577.19634205412, 53688.1946770791, 53709.73145134406, 44612.77693611641, 45565.54434455746, 45607.77196113998, 46359.3046104302, 47245.0, 43810.78094609507, 44212.55787442085, 44833.775831120634, 45465.0, 45855.31217031681, 44678.10643787041, 45300.32049519194, 45463.31805022861, 45838.541614583424, 46023.76988115038, 37238.8138059308, 38680.61319386883, 39836.20327593374, 40290.395644064774, 40864.0, 29217.707822922348, 29405.481670633417, 29753.404931901674, 29761.5535766968, 30255.848720756254], "value": 22239.055743767494, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.4959026831057, 410.4643440310394, 412.2023883784876, 425.6488397072414, 441.4283502863791, 15025.474108406208, 20233.291834785785, 20377.694334584994, 20471.897640511794, 20689.482762930933, 19364.12861421236, 19556.608867822633, 19713.704294435596, 19771.802281977176, 19882.90058549709, 5774.82675519735, 5817.243758311424, 5916.201312822757, 5956.076808094752, 5957.88084238315, 5889.734961926723, 5915.497182739472, 5919.763209471616, 5946.405359464041, 5946.613470124428, 5692.544596432296, 5849.327327357366, 5901.881962360751, 5907.6750778707155, 5909.852253693671, 5744.971275143639, 5869.178315035899, 5897.764089436418, 5911.113333000041, 5926.022206335971, 5743.310802703661, 5829.387914269026, 5829.912551311733, 5831.620944638585, 5835.679037652927, 14683.926580367137, 18219.817801821944, 20504.69242961357, 22413.327600172022, 22666.09335626573, 2412.6260429633435, 2486.2044145873147, 2492.476579918219, 2530.607755797854, 2537.936551586207, 10781.245312828105, 10795.730106747395, 10893.564257429693, 11079.72300692489, 11082.224244302903, 2081.2923605974042, 2087.1025459052603, 2094.4554415851835, 2119.3430036688524, 2165.4802847316687, 57665.0, 62521.70892671058, 70635.99999999949, 82544.17455825485, 85259.14740852636, 11247.156463265288, 11415.885841141482, 11419.0, 11491.080713542897, 11817.881821181678, 16038.0, 16048.598785030468, 16213.189340532927, 16216.10811405384, 16249.512514624581, 8320.500769953822, 8437.95781021091, 8497.516018432536, 8570.657173712982, 8703.521306328214, 8026.558539280396, 8072.959635201786, 8276.006879174538, 8288.419810613319, 8296.792580185424, 4885.1206782779445, 4977.129002424561, 4977.427595826526, 5007.599392048627, 5149.76826042829, 2046.4270004398793, 2089.206101681382, 2101.317071951622, 2125.309274485798, 2149.258505815491, 904.3036316079442, 977.1010670183448, 989.1147423056324, 1025.026225086171, 1046.9057784799252, 19342.95087861195, 19404.8059519403, 19697.409077727403, 19960.0, 20230.69653955192, 1025.092792878308, 1030.7474668706216, 1033.9689809305733, 1050.2070936442938, 1077.488193108283, 10308.793824123626, 10930.180236482296, 10960.043159129531, 11003.504842282116, 11093.722656933642, 557997.0, 560127.0, 576934.0, 586463.0, 587326.1901071487, 75462.24537754661, 86466.0, 98844.50577747065, 99739.0, 100191.0, 976.0873583199731, 1013.1793247469574, 1035.746242170658, 1041.5261056219401, 1046.8220402531588, 17595.736063959306, 17895.463136105936, 17923.28306867711, 18046.63906721832, 18100.09499525045, 30939.84530077335, 41100.1779964405, 44492.55507445014, 44557.0, 44735.328970064824, 54247.18629220486, 63226.68386658037, 63482.68258658677, 63612.68193659002, 65090.02364964435, 6349.936500635119, 8836.91163088361, 8876.51179185151, 8932.776680583038, 9191.172794448532, 11589.188756786943, 12939.805902911276, 13016.775689492044, 13113.409896554487, 13228.735425291632, 11141.88858111441, 11346.319220846775, 11437.828432573351, 11502.769944601228, 11764.117691173025, 11049.17131215146, 11330.716732081764, 11410.201285909907, 11562.075033997427, 11587.246828956091, 11586.246893951866, 11665.766684666429, 11715.414229288672, 11725.0, 11974.521019159136, 7644.388448923962, 8773.824523509622, 8911.465312081296, 8987.101289870961, 9003.63985440575, 10200.846987295048, 11429.885701142883, 11435.714107147387, 11565.826512602149, 11712.941435292769], "value": 9399.546675688447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.8299587045426, 1611.9194040297987, 1744.5464179313383, 1836.889786612804, 1852.5090850924507, 9289.44263344199, 10040.698779036622, 10047.799044019115, 10048.748781280463, 10049.09558139769, 8540.274076703488, 9234.353595248336, 9487.762805929839, 9519.857202141973, 9749.805003899935, 2456.324510759542, 2502.3744063984004, 2503.5368456835545, 2504.9373765655823, 2514.6479492871013, 2461.212412028143, 2560.9103681371157, 2570.3574106473384, 2573.472438150184, 2575.90984315549, 2473.319837044818, 2487.315988103268, 2487.7139129000125, 2489.3029951613576, 2494.276659768665, 2433.7201221859445, 2454.055188752325, 2454.2023842251338, 2455.9017639294498, 2457.741937096597, 2360.9645855312183, 2413.734489206186, 2417.5406672732124, 2418.3712234819072, 2427.7693619106158, 9585.137337639646, 9751.073648003428, 9751.756206094833, 9766.609335626566, 9769.267304952085, 5642.04085305499, 5825.1844741736195, 5830.883382332329, 5834.4165583441745, 5836.241288632493, 3315.4032274190636, 3412.539307193522, 3464.8960531184107, 3479.7564170508076, 3491.807950562718, 1128.2722643894642, 1137.6587023892798, 1140.526681427215, 1173.7945859474557, 1175.5297880847643, 13916.860831391657, 14019.929900350433, 14025.719485610332, 14054.578362649137, 14054.648633784134, 4770.141374552544, 4782.258749893772, 4792.305115758196, 4879.804807807719, 4995.0509403214, 11576.0, 11603.65189044313, 11619.6514104577, 11621.709457263467, 11623.360715160577, 5635.549156067505, 5644.463775941279, 5645.294338207724, 5647.802326918538, 5651.8304450866535, 4593.081383723269, 5125.154349532286, 5186.688798672092, 5239.580833533309, 5262.421133675292, 4283.721558098714, 4375.934360984587, 4390.824367025283, 4403.647708183337, 4483.2826747720865, 1329.3884812985941, 1362.3937347880099, 1365.4538184726189, 1366.9179849209079, 1366.9931650341885, 873.9344549158711, 961.8220629183623, 1030.8144533983955, 1040.6565833275001, 1051.5898799468214, 6346.9682651586445, 6375.458086062737, 6451.870962580722, 6507.804765857032, 6545.905310300658, 436.92572262714776, 449.6605063177346, 454.1711376737467, 454.82034596334927, 463.8399752085525, 4326.740395576276, 4346.717463364871, 4390.7585082820115, 4395.956040439554, 4398.956010439919, 11792.115591330514, 12359.69100772488, 12369.072319576067, 12378.690532736573, 12389.814152787716, 11470.426478676032, 12043.277403355827, 12043.518259269531, 12299.0, 12325.445354959049, 9047.0, 9196.494192819326, 9237.752903358096, 9251.259899208047, 9281.0, 6642.13652225227, 6874.057202225688, 6881.243063263138, 6942.8611427772175, 6955.304469553004, 10936.617218397318, 11037.503312350804, 11057.778844422906, 11362.318260904041, 11581.594644187413, 11326.660200194008, 11504.0, 11720.296782193096, 11800.881991179978, 11869.465874035517, 7791.0650721913735, 8393.790155246168, 8618.741437756877, 8663.48019118855, 8820.426672266281, 9634.084761947604, 9718.708438746566, 9736.9026309736, 9738.659146929824, 9740.220782337254, 7780.572068536285, 8252.09226985019, 8323.6670533178, 8400.49597024156, 8650.394472386872, 8372.497650141011, 8384.2873355763, 8505.106963768716, 8521.360897932556, 8748.912510875065, 7861.449698521049, 7882.921170788448, 7960.6417711201975, 7986.201379861967, 7993.400494963017, 5662.971685141712, 5708.628939118944, 5741.3971532988435, 5882.735276912635, 5967.49276311501, 5743.10981797822, 5825.854353641193, 5896.204012458264, 5926.436988486087, 5936.732847021981], "value": 4956.726836605289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.345225917517, 338.6308923273632, 350.9210427653778, 362.09295714235833, 363.7817309614232, 5568.832935011946, 5570.526505247054, 5574.581906357019, 5587.552995760339, 5598.13228949513, 5455.399906010336, 5489.258950041743, 5522.91715624266, 5525.557955363572, 5545.694986775726, 3839.1937693084346, 4118.279301122307, 4300.741955482681, 4304.720193187432, 4322.740635561876, 4255.297875850496, 4298.441202643652, 4320.891977700551, 4322.0, 4325.545817689144, 4281.8287268509375, 4297.4099583154275, 4309.2889673204045, 4310.245707001278, 4316.158349121923, 4115.8971025724295, 4196.979015104936, 4255.659547236213, 4271.337942618868, 4276.2089013532595, 3806.333891568964, 3812.1232116613332, 3818.1363977017236, 3824.9426258606145, 3834.8466061357512, 5325.840224793263, 5381.865453363658, 5391.379991301012, 5392.2181283714035, 5393.298871146765, 4800.159972004885, 4906.803727850882, 4922.163232250526, 4925.507449255082, 4951.232558953368, 5730.36965933747, 5751.712414379264, 5752.626079304831, 5758.510526605243, 5761.567882408836, 4147.170565886835, 4214.641755450791, 4216.51510076342, 4218.599233072853, 4224.78876056196, 5690.260266165414, 5785.971070144665, 5834.737436815353, 5960.552958528085, 5985.5810093293485, 4192.664586833107, 4222.239996800544, 4223.873283801491, 4232.386303985905, 4247.68142389322, 4228.429162063144, 4271.5011718144615, 4275.978620106879, 4287.335463003239, 4304.225239456865, 4667.533246675373, 4853.878653033702, 4855.393075865517, 4855.514448555116, 4863.513648635108, 5755.453231942959, 5789.913151302734, 5791.82624521265, 5813.505852002542, 5818.679972601463, 1490.5081323163547, 1499.4901733410693, 1505.5483354993678, 1506.4802643088335, 1507.7512210485365, 918.191991047887, 953.2326477185793, 972.8471760963281, 973.9432715503758, 974.6539978307741, 4949.604031677457, 4971.45314015458, 4975.577075948585, 4981.676191047571, 5007.4491805901325, 5205.126154584346, 5214.869628259248, 5216.243644671501, 5244.396894357122, 5251.632385733038, 4993.925091123636, 4995.825146119869, 5001.42483614389, 5271.973640131775, 5651.547876169897, 3137.733292670103, 3166.2401023754132, 3168.936621267562, 3171.5084161954933, 3181.284211052495, 6029.0, 6170.629762214281, 6176.085413521822, 6180.412860778219, 6222.004479283293, 6001.699915004232, 6020.518358531307, 6044.821259854374, 6065.75736970525, 6073.362296958844, 6149.692515374213, 6150.354212807591, 6162.504185804756, 6174.765046990709, 6192.0, 5616.354119276172, 5885.117232415172, 5925.0, 5942.584019118794, 5948.494377977747, 5878.911816322844, 5888.823335299948, 5910.497607703393, 5911.704414779158, 5932.762689492372, 5834.912476312944, 5915.171875937286, 5994.520438364833, 6042.607230530001, 6176.413240742122, 4187.371894215892, 4244.617984381421, 4279.122779830065, 4302.849400270976, 4307.135754723074, 5855.385184555731, 5916.201312822714, 5969.403059694169, 5971.253593300838, 5972.641641501524, 5616.550675945833, 5848.912266315923, 5850.502707269759, 5856.9121463178935, 5874.151036747089, 5823.534117270693, 5843.2403787507355, 5852.297724273115, 5859.267591551056, 5864.530837533072, 5775.711214439345, 5776.335721392094, 5799.449052340021, 5847.298324201123, 5850.444207800252, 5697.313486121192, 5859.267591551056, 5888.823335299948, 5892.499137573182, 5899.734511947058, 5719.341390996565, 5734.942650573607, 5743.984408185736, 5745.3105627324985, 5752.28096487939], "value": 4379.325080348141, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.1520980156134, 836.122071824584, 844.5903736687706, 857.4212406625522, 904.7666237241102, 16430.671386572263, 17356.04541750203, 17534.473965781017, 17541.73687394687, 17660.0, 14898.627534311647, 15222.467213647524, 15291.158986255752, 15292.617684557892, 16138.596535086628, 735.2977906099688, 875.9036505984336, 887.9556022198864, 888.1873086126187, 889.3818795936827, 861.9224269815716, 897.3808072430035, 900.4957223954574, 901.9909800902005, 902.7517432706026, 950.4677380666849, 952.8761261036055, 953.8998405167462, 954.5131982688844, 954.5656726189567, 763.7594157840263, 935.4621092871625, 936.0, 936.2556767369957, 936.3258453913205, 665.6272487407019, 683.9076724642159, 686.4508393285402, 689.007828726632, 689.6034780001469, 15967.121808300539, 16002.279897404645, 16032.198390080566, 16032.919835400866, 16050.277737501841, 15146.0, 15391.153486558234, 15806.762898556532, 15902.88679792415, 16028.519144425685, 13656.317184140753, 13834.515791947233, 13951.720965580733, 14079.929600352036, 14147.787783183261, 4649.349091127245, 4692.2726977318225, 4703.858884233478, 4753.881152971203, 4758.048390321915, 86225.56887215587, 86315.70526442108, 86741.0, 87338.0, 93733.06266937312, 11708.356040417688, 11721.472533736003, 11813.232139910879, 11878.168528203116, 12052.33712145823, 21521.89239053795, 21634.783652163278, 21654.35036948894, 21757.12971481123, 21785.564288714137, 9165.358424910326, 9209.63161473547, 9311.161995420445, 9336.95331523338, 9353.345265831327, 5107.540321371094, 5279.128943724242, 5279.551238144801, 5336.626436149432, 5532.474414930575, 3685.502457168248, 3807.0672685192303, 3811.0281878121455, 3821.464994900716, 3830.1190726132586, 675.0448115916022, 677.8847595908708, 684.3806355248508, 686.3171144711044, 715.6815217228284, 703.2931903437013, 725.4812808841598, 727.3381223086966, 729.1978823294439, 757.9469437139347, 9883.851742223873, 10280.485975701185, 10336.638217662347, 10399.428031458343, 10514.158867290598, 634.2199095113036, 642.8682120165355, 653.5000724445803, 686.3548264631231, 717.7093277222733, 4924.60603151747, 4964.230544265644, 4969.701817890938, 5004.099262132851, 5058.266551350033, 126467.94128234767, 127061.0, 129687.0, 134720.0, 134777.0, 109790.0, 112510.0, 113684.0, 114077.42961285141, 114495.0, 95176.52411737897, 98522.50738746261, 101103.4944825286, 102738.0, 103694.0, 9513.286503512403, 9627.374220675634, 9939.105480506789, 9979.0, 9991.750206244902, 57099.28702138943, 59614.70192649009, 60300.0, 61345.07982380179, 62761.05858412219, 81250.18749812426, 82427.0, 84272.57863710642, 89298.0, 92036.53981730048, 12679.556215532411, 12777.84999350063, 12796.744065118832, 13272.336383180995, 13898.09662371983, 13699.726005479635, 15026.849731502545, 15123.243837808286, 15991.840081599501, 16030.519084427486, 14586.124832510084, 14779.408823646934, 15130.697386051997, 15257.237138143271, 15996.68006639884, 14760.33578488949, 14827.777583336043, 15210.847891521385, 15260.522532348597, 15550.61123471877, 14132.929335353258, 14626.926865366031, 14659.633509162357, 14975.70048599, 15406.229688515305, 11647.0, 12209.206401583868, 12224.144309898222, 12224.572139975058, 12551.811722824348, 12512.998960083354, 12609.495620175458, 12875.163114397534, 12879.613611591667, 13030.47878084866], "value": 8242.129733742224, "unit": "score"}], "name": "dromaeo_css", "value": 7702.48768802429, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 108.0, 110.0, 111.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 121.0, 177.0, 116.0, 118.0, 142.0, 120.0, 164.0, 121.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0, 162.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 74.0, 114.0, 75.0, 76.0, 74.0, 75.0, 108.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 141.0, 84.0, 85.0, 83.0, 120.0, 84.0, 84.0, 124.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 50.0, 51.0, 50.0, 50.0, 51.0, 50.0, 50.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 71.0, 72.0, 71.0, 72.0, 72.0, 77.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 117.0, 117.0, 117.0, 115.0, 116.0, 125.0, 118.0, 159.0, 119.0], "value": 122.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 153.0, 150.0, 153.0, 151.0, 148.0, 148.0, 157.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 61.0, 59.0, 60.0, 67.0, 64.0, 60.0, 61.0, 60.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1459.1000000000004, "alertThreshold": 2.0}]} 04:12:47 INFO - Return code: 0 04:12:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:12:47 INFO - # TBPL SUCCESS # 04:12:47 INFO - Running post-action listener: _resource_record_post_action 04:12:47 INFO - [mozharness: 2016-08-14 11:12:47.852723Z] Finished run-tests step (success) 04:12:47 INFO - Running post-run listener: _resource_record_post_run 04:12:47 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328990720; Read time: 28; Write time: 223912 04:12:47 INFO - TinderboxPrint: CPU usage
12.9% 04:12:47 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 04:12:47 INFO - TinderboxPrint: I/O write bytes / time
328,990,720 / 223,912 04:12:47 INFO - TinderboxPrint: CPU idle
8,269.3 (87.1%) 04:12:47 INFO - TinderboxPrint: CPU user
1,184.1 (12.5%) 04:12:47 INFO - TinderboxPrint: Swap in / out
0 / 0 04:12:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79151104; Read time: 0; Write time: 77612 04:12:47 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249839616; Read time: 28; Write time: 146300 04:12:48 INFO - Running post-run listener: _upload_blobber_files 04:12:48 INFO - Blob upload gear active. 04:12:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:12:48 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 04:12:48 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'] 04:12:48 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 04:12:48 INFO - (blobuploader) - INFO - Open directory for files ... 04:12:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:12:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:53 INFO - (blobuploader) - INFO - Done attempting. 04:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:53 INFO - (blobuploader) - INFO - Done attempting. 04:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:12:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:12:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:12:54 INFO - (blobuploader) - INFO - Done attempting. 04:12:54 INFO - (blobuploader) - INFO - Iteration through files over. 04:12:54 INFO - Return code: 0 04:12:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:12:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:12:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de952676458ee7509cc9a10017aad23be821a853bbd143a63d7db1fd5f33465cd449991acf3bd54f66fcd22f36e0e54c5ab205dff9d209291a03bcd0e2ff3adb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e7a4869f550f171a677a2a16a83eab993027df693a3846cb134897d5939ecd163e2910e3b6f0cf0a50aecdffe68c86fe9ff930009779521e6a31fb3a646615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6de565382dd0b34efac8900795d648788e80bafe11e5b02283eb61ab8f21ba7b9f3e8a999bff098a13475d565e188bac7e8591d9e156aa885ba9f15a1197ea23"} 04:12:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:12:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:12:54 INFO - Contents: 04:12:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/de952676458ee7509cc9a10017aad23be821a853bbd143a63d7db1fd5f33465cd449991acf3bd54f66fcd22f36e0e54c5ab205dff9d209291a03bcd0e2ff3adb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e7a4869f550f171a677a2a16a83eab993027df693a3846cb134897d5939ecd163e2910e3b6f0cf0a50aecdffe68c86fe9ff930009779521e6a31fb3a646615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6de565382dd0b34efac8900795d648788e80bafe11e5b02283eb61ab8f21ba7b9f3e8a999bff098a13475d565e188bac7e8591d9e156aa885ba9f15a1197ea23"} 04:12:54 INFO - Running post-run listener: copy_logs_to_upload_dir 04:12:54 INFO - Copying logs to upload dir... 04:12:54 INFO - mkdir: /builds/slave/test/build/upload/logs 04:12:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.051122 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-14 04:12:53.020596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-14 04:12:53.025731) ========= 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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 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/de952676458ee7509cc9a10017aad23be821a853bbd143a63d7db1fd5f33465cd449991acf3bd54f66fcd22f36e0e54c5ab205dff9d209291a03bcd0e2ff3adb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e7a4869f550f171a677a2a16a83eab993027df693a3846cb134897d5939ecd163e2910e3b6f0cf0a50aecdffe68c86fe9ff930009779521e6a31fb3a646615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6de565382dd0b34efac8900795d648788e80bafe11e5b02283eb61ab8f21ba7b9f3e8a999bff098a13475d565e188bac7e8591d9e156aa885ba9f15a1197ea23"} build_url:https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/ZDK-BU_6SWeo4eZxfi0MGw/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/de952676458ee7509cc9a10017aad23be821a853bbd143a63d7db1fd5f33465cd449991acf3bd54f66fcd22f36e0e54c5ab205dff9d209291a03bcd0e2ff3adb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e7a4869f550f171a677a2a16a83eab993027df693a3846cb134897d5939ecd163e2910e3b6f0cf0a50aecdffe68c86fe9ff930009779521e6a31fb3a646615", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6de565382dd0b34efac8900795d648788e80bafe11e5b02283eb61ab8f21ba7b9f3e8a999bff098a13475d565e188bac7e8591d9e156aa885ba9f15a1197ea23"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-14 04:12:53.051463) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 04:12:53.051808) ========= 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=523ca41ca1e0623de333ad52000001bf-1471169134.364402-1551794655 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-14 04:12:53.135524) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-14 04:12:53.135856) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-14 04:12:53.136184) ========= ========= Total master_lag: 0.18 =========