builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1483029950.25 results: success (0) buildid: 20161229070720 builduid: ce7a4d9eb2054739ad2a63e4b570a8ef revision: 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.253508) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.253999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.254304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.269184) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.269582) ========= 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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 08:45:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-12-29 08:45:50 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107878 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.391164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.391492) ========= 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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015549 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:50.441409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 08:45:50.441772) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 --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 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 --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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 08:45:50,758 truncating revision to first 12 chars 2016-12-29 08:45:50,758 Setting DEBUG logging. 2016-12-29 08:45:50,758 attempt 1/10 2016-12-29 08:45:50,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6ece83105829?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 08:45:51,432 unpacking tar archive at: mozilla-aurora-6ece83105829/testing/mozharness/ program finished with exit code 0 elapsedTime=1.008334 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 08:45:51.463578) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:51.463918) ========= script_repo_revision: 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:51.464352) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:51.464672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 08:45:51.474122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-29 08:45:51.474532) ========= /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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:45:51 INFO - MultiFileLogger online at 20161229 08:45:51 in /builds/slave/test 08:45:51 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 08:45:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:51 INFO - {'append_to_log': False, 08:45:51 INFO - 'base_work_dir': '/builds/slave/test', 08:45:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:45:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:51 INFO - 'branch': 'Mozilla-Aurora', 08:45:51 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:51 INFO - 'config_files': ('talos/linux_config.py',), 08:45:51 INFO - 'default_actions': ('clobber', 08:45:51 INFO - 'read-buildbot-config', 08:45:51 INFO - 'download-and-extract', 08:45:51 INFO - 'populate-webroot', 08:45:51 INFO - 'create-virtualenv', 08:45:51 INFO - 'install', 08:45:51 INFO - 'run-tests'), 08:45:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:51 INFO - 'download_minidump_stackwalk': True, 08:45:51 INFO - 'download_symbols': 'ondemand', 08:45:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:51 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:51 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:51 INFO - 'installer_path': 'installer.exe', 08:45:51 INFO - 'log_level': 'info', 08:45:51 INFO - 'log_name': 'talos', 08:45:51 INFO - 'log_to_console': True, 08:45:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:45:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:45:51 INFO - 'opt_config_files': (), 08:45:51 INFO - 'pip_index': False, 08:45:51 INFO - 'sps_profile': False, 08:45:51 INFO - 'sps_profile_interval': 0, 08:45:51 INFO - 'suite': 'dromaeojs', 08:45:51 INFO - 'system_bits': '32', 08:45:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:51 INFO - 'title': 'talos-linux64-ix-038', 08:45:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:51 INFO - 'use_talos_json': True, 08:45:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:51 INFO - 'work_dir': 'build'} 08:45:51 INFO - [mozharness: 2016-12-29 16:45:51.881715Z] Running clobber step. 08:45:51 INFO - Running pre-action listener: _resource_record_pre_action 08:45:51 INFO - Running main action method: clobber 08:45:51 INFO - rmtree: /builds/slave/test/build 08:45:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:53 INFO - Running post-action listener: _resource_record_post_action 08:45:53 INFO - [mozharness: 2016-12-29 16:45:53.120329Z] Finished clobber step (success) 08:45:53 INFO - [mozharness: 2016-12-29 16:45:53.120428Z] Running read-buildbot-config step. 08:45:53 INFO - Running pre-action listener: _resource_record_pre_action 08:45:53 INFO - Running main action method: read_buildbot_config 08:45:53 INFO - Using buildbot properties: 08:45:53 INFO - { 08:45:53 INFO - "project": "", 08:45:53 INFO - "product": "firefox", 08:45:53 INFO - "script_repo_revision": "production", 08:45:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:45:53 INFO - "repository": "", 08:45:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:45:53 INFO - "buildid": "20161229070720", 08:45:53 INFO - "pgo_build": "True", 08:45:53 INFO - "basedir": "/builds/slave/test", 08:45:53 INFO - "buildnumber": 664, 08:45:53 INFO - "slavename": "talos-linux64-ix-038", 08:45:53 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:45:53 INFO - "platform": "ubuntu64_hw", 08:45:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:45:53 INFO - "branch": "mozilla-aurora", 08:45:53 INFO - "repo_path": "releases/mozilla-aurora", 08:45:53 INFO - "revision": "6ece83105829aa5a737ccfadb55f9f1d46de8ab3", 08:45:53 INFO - "stage_platform": "linux64-pgo", 08:45:53 INFO - "builduid": "ce7a4d9eb2054739ad2a63e4b570a8ef", 08:45:53 INFO - "slavebuilddir": "test" 08:45:53 INFO - } 08:45:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 08:45:53 INFO - Running post-action listener: _resource_record_post_action 08:45:53 INFO - [mozharness: 2016-12-29 16:45:53.122873Z] Finished read-buildbot-config step (success) 08:45:53 INFO - [mozharness: 2016-12-29 16:45:53.122959Z] Running download-and-extract step. 08:45:53 INFO - Running pre-action listener: _resource_record_pre_action 08:45:53 INFO - Running main action method: download_and_extract 08:45:53 INFO - mkdir: /builds/slave/test/build/tests 08:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:53 INFO - trying https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 08:45:53 INFO - Downloading https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 08:45:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:45:53 INFO - Downloaded 1361 bytes. 08:45:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 08:45:53 INFO - Using the following test package requirements: 08:45:53 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 08:45:53 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:45:53 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:45:53 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'jsshell-linux-x86_64.zip'], 08:45:53 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:45:53 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 08:45:53 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:45:53 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:45:53 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:45:53 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 08:45:53 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:45:53 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:45:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 08:45:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:45:53 INFO - Fetch https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:45:54 INFO - Http code: 200 08:45:54 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 08:45:54 INFO - x-amz-cf-id: jWmLSqn_-71C6r0DXkhZHN5lgcbQTpMPL2P-iPDVctdjBKQ5A9X0QQ== 08:45:54 INFO - x-amz-version-id: 1ZlLJUPrvZaximGfiVqxnEbsUqMI6EV1 08:45:54 INFO - Expected file size: 21941419 08:45:54 INFO - Obtained file size: 21941419 08:45:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 08:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 08:45:59 INFO - Http code: 200 08:45:59 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:45:59 INFO - x-amz-cf-id: 2p6IBI1zEjA_4TFnPbkFj8OwrYEELQqZiwcUE9zpizCS2ydntWqv6w== 08:45:59 INFO - x-amz-version-id: 1ZlLJUPrvZaximGfiVqxnEbsUqMI6EV1 08:45:59 INFO - Expected file size: 21941419 08:45:59 INFO - Obtained file size: 21941419 08:46:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 08:46:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:46:03 INFO - Fetch https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 08:46:04 INFO - Http code: 200 08:46:04 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 08:46:04 INFO - x-amz-cf-id: Z4BcE-qLrpR8lj2ohvUSX1KZaAdCYrG2t6UPIIU3_7RikxvKdxVZfA== 08:46:04 INFO - x-amz-version-id: iAjuPMZKP58lrmuCno0Efn2OJh6TCbrq 08:46:04 INFO - Expected file size: 14409038 08:46:04 INFO - Obtained file size: 14409038 08:46:04 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:04 INFO - trying https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 08:46:04 INFO - Downloading https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 08:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:46:05 INFO - Downloaded 64022125 bytes. 08:46:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 08:46:05 INFO - mkdir: /builds/slave/test/properties 08:46:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:05 INFO - Contents: 08:46:05 INFO - build_url:https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 08:46:06 INFO - Running post-action listener: _resource_record_post_action 08:46:06 INFO - Running post-action listener: set_extra_try_arguments 08:46:06 INFO - [mozharness: 2016-12-29 16:46:06.586048Z] Finished download-and-extract step (success) 08:46:06 INFO - [mozharness: 2016-12-29 16:46:06.586275Z] Running populate-webroot step. 08:46:06 INFO - Running pre-action listener: _resource_record_pre_action 08:46:06 INFO - Running main action method: populate_webroot 08:46:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:06 INFO - '--tppagecycles', 08:46:06 INFO - '7'], 08:46:06 INFO - 'tests': ['tsvgm']}}, 08:46:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['damp', 'tps']}, 08:46:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'tests': ['damp', 'tps']}, 08:46:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['dromaeo_dom']}, 08:46:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['basic_compositor_video']}, 08:46:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['a11yr', 08:46:06 INFO - 'ts_paint', 08:46:06 INFO - 'tpaint', 08:46:06 INFO - 'sessionrestore', 08:46:06 INFO - 'sessionrestore_no_auto_restore', 08:46:06 INFO - 'tabpaint']}, 08:46:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['tsvgx', 08:46:06 INFO - 'tsvgr_opacity', 08:46:06 INFO - 'tart', 08:46:06 INFO - 'tscrollx', 08:46:06 INFO - 'cart']}, 08:46:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:06 INFO - 'tsvgr_opacity', 08:46:06 INFO - 'tart', 08:46:06 INFO - 'tscrollx', 08:46:06 INFO - 'cart']}, 08:46:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'talos_options': ['--disable-e10s'], 08:46:06 INFO - 'tests': ['tp5o']}, 08:46:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'tests': ['tp5o']}, 08:46:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'talos_options': ['--disable-e10s', 08:46:06 INFO - '--xperf_path', 08:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:06 INFO - 'tests': ['tp5n']}, 08:46:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:06 INFO - 'talos_options': ['--xperf_path', 08:46:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:06 INFO - 'tests': ['tp5n']}}, 08:46:06 INFO - 'talos.zip': {'path': '', 08:46:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:06 INFO - Running post-action listener: _resource_record_post_action 08:46:06 INFO - [mozharness: 2016-12-29 16:46:06.608794Z] Finished populate-webroot step (success) 08:46:06 INFO - [mozharness: 2016-12-29 16:46:06.608881Z] Running create-virtualenv step. 08:46:06 INFO - Running pre-action listener: _resource_record_pre_action 08:46:06 INFO - Running main action method: create_virtualenv 08:46:06 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:06 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 08:46:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:06 INFO - Using real prefix '/usr' 08:46:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:07 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:09 INFO - Installing pip.................done. 08:46:09 INFO - Return code: 0 08:46:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:09 INFO - Reading from file tmpfile_stdout 08:46:09 INFO - Output received: 08:46:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:46:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:09 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:09 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 08:46:09 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 08:46:09 INFO - Using env: {'DISPLAY': ':0', 08:46:09 INFO - 'HOME': '/home/cltbld', 08:46:09 INFO - 'LANG': 'en_US.UTF-8', 08:46:09 INFO - 'LANGUAGE': 'en_US:en', 08:46:09 INFO - 'LOGNAME': 'cltbld', 08:46:09 INFO - 'MAIL': '/var/mail/cltbld', 08:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:09 INFO - 'MOZ_NO_REMOTE': '1', 08:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:09 INFO - 'NO_EM_RESTART': '1', 08:46:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:09 INFO - 'PWD': '/builds/slave/test', 08:46:09 INFO - 'SHELL': '/bin/bash', 08:46:09 INFO - 'SHLVL': '1', 08:46:09 INFO - 'TERM': 'linux', 08:46:09 INFO - 'TMOUT': '86400', 08:46:09 INFO - 'USER': 'cltbld', 08:46:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:09 INFO - '_': '/tools/buildbot/bin/python'} 08:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:10 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 08:46:10 INFO - Cleaning up... 08:46:10 INFO - Return code: 0 08:46:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:10 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:10 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 08:46:10 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 08:46:10 INFO - Using env: {'DISPLAY': ':0', 08:46:10 INFO - 'HOME': '/home/cltbld', 08:46:10 INFO - 'LANG': 'en_US.UTF-8', 08:46:10 INFO - 'LANGUAGE': 'en_US:en', 08:46:10 INFO - 'LOGNAME': 'cltbld', 08:46:10 INFO - 'MAIL': '/var/mail/cltbld', 08:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:10 INFO - 'MOZ_NO_REMOTE': '1', 08:46:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:10 INFO - 'NO_EM_RESTART': '1', 08:46:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:10 INFO - 'PWD': '/builds/slave/test', 08:46:10 INFO - 'SHELL': '/bin/bash', 08:46:10 INFO - 'SHLVL': '1', 08:46:10 INFO - 'TERM': 'linux', 08:46:10 INFO - 'TMOUT': '86400', 08:46:10 INFO - 'USER': 'cltbld', 08:46:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:10 INFO - '_': '/tools/buildbot/bin/python'} 08:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:10 INFO - Downloading/unpacking psutil>=3.1.1 08:46:10 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 08:46:10 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 08:46:10 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 08:46:10 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 08:46:10 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 08:46:10 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 08:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:12 INFO - Installing collected packages: psutil 08:46:12 INFO - Running setup.py install for psutil 08:46:12 INFO - building 'psutil._psutil_linux' extension 08:46:12 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 08:46:12 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 08:46:12 INFO - building 'psutil._psutil_posix' extension 08:46:12 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 08:46:12 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 08:46:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:12 INFO - Successfully installed psutil 08:46:12 INFO - Cleaning up... 08:46:12 INFO - Return code: 0 08:46:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:12 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:12 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 08:46:12 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 08:46:12 INFO - Using env: {'DISPLAY': ':0', 08:46:12 INFO - 'HOME': '/home/cltbld', 08:46:12 INFO - 'LANG': 'en_US.UTF-8', 08:46:12 INFO - 'LANGUAGE': 'en_US:en', 08:46:12 INFO - 'LOGNAME': 'cltbld', 08:46:12 INFO - 'MAIL': '/var/mail/cltbld', 08:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:12 INFO - 'MOZ_NO_REMOTE': '1', 08:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:12 INFO - 'NO_EM_RESTART': '1', 08:46:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:12 INFO - 'PWD': '/builds/slave/test', 08:46:12 INFO - 'SHELL': '/bin/bash', 08:46:12 INFO - 'SHLVL': '1', 08:46:12 INFO - 'TERM': 'linux', 08:46:12 INFO - 'TMOUT': '86400', 08:46:12 INFO - 'USER': 'cltbld', 08:46:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:12 INFO - '_': '/tools/buildbot/bin/python'} 08:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:13 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 08:46:13 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 08:46:13 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 08:46:13 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 08:46:13 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 08:46:13 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 08:46:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:15 INFO - Installing collected packages: mozsystemmonitor 08:46:15 INFO - Running setup.py install for mozsystemmonitor 08:46:15 INFO - Successfully installed mozsystemmonitor 08:46:15 INFO - Cleaning up... 08:46:15 INFO - Return code: 0 08:46:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:15 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:15 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 08:46:15 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 08:46:15 INFO - Using env: {'DISPLAY': ':0', 08:46:15 INFO - 'HOME': '/home/cltbld', 08:46:15 INFO - 'LANG': 'en_US.UTF-8', 08:46:15 INFO - 'LANGUAGE': 'en_US:en', 08:46:15 INFO - 'LOGNAME': 'cltbld', 08:46:15 INFO - 'MAIL': '/var/mail/cltbld', 08:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:15 INFO - 'MOZ_NO_REMOTE': '1', 08:46:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:15 INFO - 'NO_EM_RESTART': '1', 08:46:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:15 INFO - 'PWD': '/builds/slave/test', 08:46:15 INFO - 'SHELL': '/bin/bash', 08:46:15 INFO - 'SHLVL': '1', 08:46:15 INFO - 'TERM': 'linux', 08:46:15 INFO - 'TMOUT': '86400', 08:46:15 INFO - 'USER': 'cltbld', 08:46:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:15 INFO - '_': '/tools/buildbot/bin/python'} 08:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:15 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:15 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 08:46:15 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 08:46:15 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 08:46:15 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 08:46:15 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 08:46:15 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 08:46:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:17 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 08:46:17 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 08:46:17 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 08:46:17 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 08:46:17 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 08:46:17 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 08:46:17 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:17 INFO - warning: no files found matching '*.txt' 08:46:17 INFO - no previously-included directories found matching 'build' 08:46:17 INFO - no previously-included directories found matching 'dist' 08:46:17 INFO - no previously-included directories found matching '.git*' 08:46:17 INFO - Installing collected packages: jsonschema, functools32 08:46:17 INFO - Running setup.py install for functools32 08:46:17 INFO - warning: no files found matching '*.txt' 08:46:17 INFO - no previously-included directories found matching 'build' 08:46:17 INFO - no previously-included directories found matching 'dist' 08:46:17 INFO - no previously-included directories found matching '.git*' 08:46:17 INFO - Successfully installed jsonschema functools32 08:46:17 INFO - Cleaning up... 08:46:18 INFO - Return code: 0 08:46:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:18 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:18 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 08:46:18 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 08:46:18 INFO - Using env: {'DISPLAY': ':0', 08:46:18 INFO - 'HOME': '/home/cltbld', 08:46:18 INFO - 'LANG': 'en_US.UTF-8', 08:46:18 INFO - 'LANGUAGE': 'en_US:en', 08:46:18 INFO - 'LOGNAME': 'cltbld', 08:46:18 INFO - 'MAIL': '/var/mail/cltbld', 08:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:18 INFO - 'MOZ_NO_REMOTE': '1', 08:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:18 INFO - 'NO_EM_RESTART': '1', 08:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:18 INFO - 'PWD': '/builds/slave/test', 08:46:18 INFO - 'SHELL': '/bin/bash', 08:46:18 INFO - 'SHLVL': '1', 08:46:18 INFO - 'TERM': 'linux', 08:46:18 INFO - 'TMOUT': '86400', 08:46:18 INFO - 'USER': 'cltbld', 08:46:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:18 INFO - '_': '/tools/buildbot/bin/python'} 08:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:18 INFO - Cleaning up... 08:46:18 INFO - Return code: 0 08:46:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:18 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:18 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:18 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 08:46:18 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 08:46:18 INFO - Using env: {'DISPLAY': ':0', 08:46:18 INFO - 'HOME': '/home/cltbld', 08:46:18 INFO - 'LANG': 'en_US.UTF-8', 08:46:18 INFO - 'LANGUAGE': 'en_US:en', 08:46:18 INFO - 'LOGNAME': 'cltbld', 08:46:18 INFO - 'MAIL': '/var/mail/cltbld', 08:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:18 INFO - 'MOZ_NO_REMOTE': '1', 08:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:18 INFO - 'NO_EM_RESTART': '1', 08:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:18 INFO - 'PWD': '/builds/slave/test', 08:46:18 INFO - 'SHELL': '/bin/bash', 08:46:18 INFO - 'SHLVL': '1', 08:46:18 INFO - 'TERM': 'linux', 08:46:18 INFO - 'TMOUT': '86400', 08:46:18 INFO - 'USER': 'cltbld', 08:46:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:18 INFO - '_': '/tools/buildbot/bin/python'} 08:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:18 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:18 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 08:46:18 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 08:46:18 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 08:46:18 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 08:46:18 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 08:46:18 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 08:46:20 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:20 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 08:46:21 INFO - Downloading docopt-0.6.1.tar.gz 08:46:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:21 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:21 INFO - Running setup.py install for blobuploader 08:46:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:21 INFO - Running setup.py install for requests 08:46:21 INFO - Running setup.py install for docopt 08:46:21 INFO - Successfully installed blobuploader requests docopt 08:46:21 INFO - Cleaning up... 08:46:21 INFO - Return code: 0 08:46:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:21 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:21 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 08:46:21 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 08:46:21 INFO - Using env: {'DISPLAY': ':0', 08:46:21 INFO - 'HOME': '/home/cltbld', 08:46:21 INFO - 'LANG': 'en_US.UTF-8', 08:46:21 INFO - 'LANGUAGE': 'en_US:en', 08:46:21 INFO - 'LOGNAME': 'cltbld', 08:46:21 INFO - 'MAIL': '/var/mail/cltbld', 08:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:21 INFO - 'MOZ_NO_REMOTE': '1', 08:46:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:21 INFO - 'NO_EM_RESTART': '1', 08:46:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:21 INFO - 'PWD': '/builds/slave/test', 08:46:21 INFO - 'SHELL': '/bin/bash', 08:46:21 INFO - 'SHLVL': '1', 08:46:21 INFO - 'TERM': 'linux', 08:46:21 INFO - 'TMOUT': '86400', 08:46:21 INFO - 'USER': 'cltbld', 08:46:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:21 INFO - '_': '/tools/buildbot/bin/python'} 08:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:22 INFO - Running setup.py (path:/tmp/pip-rcoxqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:22 INFO - Running setup.py (path:/tmp/pip-ZsreCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:22 INFO - Running setup.py (path:/tmp/pip-S2SjGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:22 INFO - Running setup.py (path:/tmp/pip-TIjJAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:22 INFO - Running setup.py (path:/tmp/pip-OD9U2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:22 INFO - Running setup.py (path:/tmp/pip-9CmVz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:22 INFO - Running setup.py (path:/tmp/pip-WUrcOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:22 INFO - Running setup.py (path:/tmp/pip-KBmalL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:22 INFO - Running setup.py (path:/tmp/pip-8PVciH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:22 INFO - Running setup.py (path:/tmp/pip-fZrYQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:22 INFO - Running setup.py (path:/tmp/pip-qNYU5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:22 INFO - Running setup.py (path:/tmp/pip-Lttphh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:23 INFO - Running setup.py (path:/tmp/pip-wte9yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:23 INFO - Running setup.py (path:/tmp/pip-6eag8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:23 INFO - Running setup.py (path:/tmp/pip-BAJKSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:23 INFO - Running setup.py (path:/tmp/pip-058GOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:23 INFO - Running setup.py (path:/tmp/pip-wXt2eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:23 INFO - Running setup.py install for manifestparser 08:46:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:23 INFO - Running setup.py install for mozcrash 08:46:23 INFO - Running setup.py install for mozdebug 08:46:23 INFO - Running setup.py install for mozdevice 08:46:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:46:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:23 INFO - Running setup.py install for mozfile 08:46:23 INFO - Running setup.py install for mozhttpd 08:46:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:23 INFO - Running setup.py install for mozinfo 08:46:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for mozInstall 08:46:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for mozleak 08:46:24 INFO - Running setup.py install for mozlog 08:46:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for moznetwork 08:46:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for mozprocess 08:46:24 INFO - Running setup.py install for mozprofile 08:46:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for mozrunner 08:46:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:24 INFO - Running setup.py install for mozscreenshot 08:46:24 INFO - Running setup.py install for moztest 08:46:25 INFO - Running setup.py install for mozversion 08:46:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:25 INFO - Cleaning up... 08:46:25 INFO - Return code: 0 08:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:25 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:25 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 08:46:25 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 08:46:25 INFO - Using env: {'DISPLAY': ':0', 08:46:25 INFO - 'HOME': '/home/cltbld', 08:46:25 INFO - 'LANG': 'en_US.UTF-8', 08:46:25 INFO - 'LANGUAGE': 'en_US:en', 08:46:25 INFO - 'LOGNAME': 'cltbld', 08:46:25 INFO - 'MAIL': '/var/mail/cltbld', 08:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:25 INFO - 'MOZ_NO_REMOTE': '1', 08:46:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:25 INFO - 'NO_EM_RESTART': '1', 08:46:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:25 INFO - 'PWD': '/builds/slave/test', 08:46:25 INFO - 'SHELL': '/bin/bash', 08:46:25 INFO - 'SHLVL': '1', 08:46:25 INFO - 'TERM': 'linux', 08:46:25 INFO - 'TMOUT': '86400', 08:46:25 INFO - 'USER': 'cltbld', 08:46:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:25 INFO - '_': '/tools/buildbot/bin/python'} 08:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:25 INFO - Running setup.py (path:/tmp/pip-sNcWl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:25 INFO - Running setup.py (path:/tmp/pip-Ip_gLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:25 INFO - Running setup.py (path:/tmp/pip-lEhLkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:25 INFO - Running setup.py (path:/tmp/pip-DoeOfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:25 INFO - Running setup.py (path:/tmp/pip-wsZWcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:25 INFO - Running setup.py (path:/tmp/pip-mFPMrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:25 INFO - Running setup.py (path:/tmp/pip-u32_cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:25 INFO - Running setup.py (path:/tmp/pip-_ja4hJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:25 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)) 08:46:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:25 INFO - Running setup.py (path:/tmp/pip-J0hyOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:26 INFO - Running setup.py (path:/tmp/pip-s3TAdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:26 INFO - Running setup.py (path:/tmp/pip-OX9wBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:26 INFO - Running setup.py (path:/tmp/pip-Buy3ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:26 INFO - Running setup.py (path:/tmp/pip-pviOji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:26 INFO - Running setup.py (path:/tmp/pip-uskj93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:26 INFO - Running setup.py (path:/tmp/pip-w_z1mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:26 INFO - Running setup.py (path:/tmp/pip-LQz1Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:26 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)) 08:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:26 INFO - Running setup.py (path:/tmp/pip-HbGtSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:26 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)) 08:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:26 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)) 08:46:26 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)) 08:46:26 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)) 08:46:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:26 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 08:46:26 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 08:46:26 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 08:46:26 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 08:46:26 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 08:46:26 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 08:46:28 INFO - Downloading blessings-1.6.tar.gz 08:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:28 INFO - Installing collected packages: blessings 08:46:28 INFO - Running setup.py install for blessings 08:46:28 INFO - Successfully installed blessings 08:46:28 INFO - Cleaning up... 08:46:28 INFO - Return code: 0 08:46:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:29 INFO - Reading from file tmpfile_stdout 08:46:29 INFO - Current package versions: 08:46:29 INFO - argparse == 1.2.1 08:46:29 INFO - blessings == 1.6 08:46:29 INFO - blobuploader == 1.2.4 08:46:29 INFO - docopt == 0.6.1 08:46:29 INFO - functools32 == 3.2.3-2 08:46:29 INFO - jsonschema == 2.5.1 08:46:29 INFO - manifestparser == 1.1 08:46:29 INFO - mozInstall == 1.12 08:46:29 INFO - mozcrash == 1.0 08:46:29 INFO - mozdebug == 0.1 08:46:29 INFO - mozdevice == 0.48 08:46:29 INFO - mozfile == 1.2 08:46:29 INFO - mozhttpd == 0.7 08:46:29 INFO - mozinfo == 0.9 08:46:29 INFO - mozleak == 0.1 08:46:29 INFO - mozlog == 3.4 08:46:29 INFO - moznetwork == 0.27 08:46:29 INFO - mozprocess == 0.23 08:46:29 INFO - mozprofile == 0.28 08:46:29 INFO - mozrunner == 6.13 08:46:29 INFO - mozscreenshot == 0.1 08:46:29 INFO - mozsystemmonitor == 0.3 08:46:29 INFO - moztest == 0.7 08:46:29 INFO - mozversion == 1.4 08:46:29 INFO - psutil == 3.1.1 08:46:29 INFO - requests == 1.2.3 08:46:29 INFO - wsgiref == 0.1.2 08:46:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:29 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 08:46:29 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 08:46:29 INFO - Using env: {'DISPLAY': ':0', 08:46:29 INFO - 'HOME': '/home/cltbld', 08:46:29 INFO - 'LANG': 'en_US.UTF-8', 08:46:29 INFO - 'LANGUAGE': 'en_US:en', 08:46:29 INFO - 'LOGNAME': 'cltbld', 08:46:29 INFO - 'MAIL': '/var/mail/cltbld', 08:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:29 INFO - 'MOZ_NO_REMOTE': '1', 08:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:29 INFO - 'NO_EM_RESTART': '1', 08:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:29 INFO - 'PWD': '/builds/slave/test', 08:46:29 INFO - 'SHELL': '/bin/bash', 08:46:29 INFO - 'SHLVL': '1', 08:46:29 INFO - 'TERM': 'linux', 08:46:29 INFO - 'TMOUT': '86400', 08:46:29 INFO - 'USER': 'cltbld', 08:46:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:29 INFO - '_': '/tools/buildbot/bin/python'} 08:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 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)) 08:46:29 INFO - Cleaning up... 08:46:29 INFO - Return code: 0 08:46:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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 0x7fbbea465f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, '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': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', '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 08:46:29 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 08:46:29 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 08:46:29 INFO - Using env: {'DISPLAY': ':0', 08:46:29 INFO - 'HOME': '/home/cltbld', 08:46:29 INFO - 'LANG': 'en_US.UTF-8', 08:46:29 INFO - 'LANGUAGE': 'en_US:en', 08:46:29 INFO - 'LOGNAME': 'cltbld', 08:46:29 INFO - 'MAIL': '/var/mail/cltbld', 08:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:29 INFO - 'MOZ_NO_REMOTE': '1', 08:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:29 INFO - 'NO_EM_RESTART': '1', 08:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:29 INFO - 'PWD': '/builds/slave/test', 08:46:29 INFO - 'SHELL': '/bin/bash', 08:46:29 INFO - 'SHLVL': '1', 08:46:29 INFO - 'TERM': 'linux', 08:46:29 INFO - 'TMOUT': '86400', 08:46:29 INFO - 'USER': 'cltbld', 08:46:29 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:29 INFO - '_': '/tools/buildbot/bin/python'} 08:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:29 INFO - Cleaning up... 08:46:29 INFO - Return code: 0 08:46:29 INFO - Running post-action listener: _resource_record_post_action 08:46:29 INFO - Running post-action listener: _start_resource_monitoring 08:46:29 INFO - Starting resource monitoring. 08:46:29 INFO - [mozharness: 2016-12-29 16:46:29.621324Z] Finished create-virtualenv step (success) 08:46:29 INFO - [mozharness: 2016-12-29 16:46:29.621782Z] Running install step. 08:46:29 INFO - Running pre-action listener: _resource_record_pre_action 08:46:29 INFO - Running main action method: install 08:46:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:29 INFO - Reading from file tmpfile_stdout 08:46:29 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:29 INFO - Reading from file tmpfile_stdout 08:46:29 INFO - Output received: 08:46:29 INFO - Usage: mozinstall [options] installer 08:46:29 INFO - Options: 08:46:29 INFO - -h, --help show this help message and exit 08:46:29 INFO - -d DEST, --destination=DEST 08:46:29 INFO - Directory to install application into. [default: 08:46:29 INFO - "/builds/slave/test"] 08:46:29 INFO - --app=APP Application being installed. [default: firefox] 08:46:29 INFO - mkdir: /builds/slave/test/build/application 08:46:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:46:45 INFO - Reading from file tmpfile_stdout 08:46:45 INFO - Output received: 08:46:45 INFO - /builds/slave/test/build/application/firefox/firefox 08:46:45 INFO - Running post-action listener: _resource_record_post_action 08:46:45 INFO - [mozharness: 2016-12-29 16:46:45.849073Z] Finished install step (success) 08:46:45 INFO - [mozharness: 2016-12-29 16:46:45.849325Z] Running run-tests step. 08:46:45 INFO - Running pre-action listener: _resource_record_pre_action 08:46:45 INFO - Running main action method: run_tests 08:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:46:45 INFO - Python 2.7.3 08:46:45 INFO - Return code: 0 08:46:45 INFO - grabbing minidump binary from tooltool 08:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:45 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 0x11c6f60>, '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 0x11c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:46:45 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 08:46:45 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 08:46:45 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 08:46:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:46:45 INFO - Return code: 0 08:46:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:46:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:46:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:46:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:46:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:46:45 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/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 08:46:45 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/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 08:46:45 INFO - Using env: {'DISPLAY': ':0', 08:46:45 INFO - 'HOME': '/home/cltbld', 08:46:45 INFO - 'LANG': 'en_US.UTF-8', 08:46:45 INFO - 'LANGUAGE': 'en_US:en', 08:46:45 INFO - 'LOGNAME': 'cltbld', 08:46:45 INFO - 'MAIL': '/var/mail/cltbld', 08:46:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:46:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:45 INFO - 'MOZ_NO_REMOTE': '1', 08:46:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:45 INFO - 'NO_EM_RESTART': '1', 08:46:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:45 INFO - 'PWD': '/builds/slave/test', 08:46:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:46:45 INFO - 'SHELL': '/bin/bash', 08:46:45 INFO - 'SHLVL': '1', 08:46:45 INFO - 'TERM': 'linux', 08:46:45 INFO - 'TMOUT': '86400', 08:46:45 INFO - 'USER': 'cltbld', 08:46:45 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399', 08:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:45 INFO - '_': '/tools/buildbot/bin/python'} 08:46:45 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/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 08:46:46 INFO - mozversion application_buildid: 20161229070720 08:46:46 INFO - mozversion application_changeset: 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 08:46:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:46:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:46:46 INFO - mozversion application_name: Firefox 08:46:46 INFO - mozversion application_remotingname: firefox-dev 08:46:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:46:46 INFO - mozversion application_vendor: Mozilla 08:46:46 INFO - mozversion application_version: 52.0a2 08:46:46 INFO - mozversion platform_buildid: 20161229070720 08:46:46 INFO - mozversion platform_changeset: 6ece83105829aa5a737ccfadb55f9f1d46de8ab3 08:46:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:46:46 INFO - mozversion platform_version: 52.0a2 08:46:46 INFO - using testdate: 1483030006 08:46:46 INFO - actual date: 1483030006 08:46:46 INFO - starting webserver on 'localhost:42341' 08:46:46 INFO - SUITE-START | Running 2 tests 08:46:46 INFO - TEST-START | dromaeo_css 08:46:46 INFO - operating with platform_type : linux_ 08:46:46 INFO - Initialising browser for dromaeo_css test... 08:46:46 INFO - TEST-INFO | started process 28767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSIqZKo/profile http://localhost:42341/getInfo.html) 08:46:50 INFO - PROCESS | 28767 | __metrics Screen width/height:1600/1200 08:46:50 INFO - PROCESS | 28767 | colorDepth:24 08:46:50 INFO - PROCESS | 28767 | Browser inner width/height: 1024/697 08:46:50 INFO - PROCESS | 28767 | __metrics 08:46:51 INFO - PROCESS | 28767 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:46:51 INFO - PROCESS | 28767 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:46:51 INFO - PROCESS | 28767 | console.error: PushService: 08:46:51 INFO - PROCESS | 28767 | stateChangeProcessEnqueue: Error transitioning state 08:46:51 INFO - PROCESS | 28767 | UnknownError 08:46:52 INFO - TEST-INFO | 28767: exit 0 08:46:52 INFO - Browser initialized. 08:46:52 INFO - Running cycle 1/1 for dromaeo_css test... 08:46:52 INFO - TEST-INFO | started process 28858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSIqZKo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:04 INFO - PROCESS | 28858 | RSS: Main: 147865600 08:47:04 INFO - PROCESS | 28858 | 08:47:55 INFO - PROCESS | 28858 | 1483030075587 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] 08:50:06 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42341/tests/dromaeo/cssquery-ext.html) 08:50:07 INFO - PROCESS | 28858 | RSS: Main: 223518720 08:50:07 INFO - PROCESS | 28858 | 08:53:09 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-ext.html (next: http://localhost:42341/tests/dromaeo/cssquery-jquery.html) 08:53:09 INFO - PROCESS | 28858 | RSS: Main: 236232704 08:53:09 INFO - PROCESS | 28858 | 08:56:11 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42341/tests/dromaeo/cssquery-mootools.html) 08:56:12 INFO - PROCESS | 28858 | RSS: Main: 220303360 08:56:12 INFO - PROCESS | 28858 | 08:59:14 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42341/tests/dromaeo/cssquery-prototype.html) 08:59:15 INFO - PROCESS | 28858 | RSS: Main: 174333952 08:59:15 INFO - PROCESS | 28858 | 09:02:17 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42341/tests/dromaeo/cssquery-yui.html) 09:02:18 INFO - PROCESS | 28858 | RSS: Main: 178147328 09:02:18 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | Cycle 1(1): loaded http://localhost:42341/tests/dromaeo/cssquery-yui.html (next: http://localhost:42341/tests/dromaeo/cssquery-dojo.html) 09:05:20 INFO - PROCESS | 28858 | RSS: Main: 267751424 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | __start_tp_report 09:05:20 INFO - PROCESS | 28858 | _x_x_mozilla_page_load 09:05:20 INFO - PROCESS | 28858 | _x_x_mozilla_page_load_details 09:05:20 INFO - PROCESS | 28858 | |i|pagename|runs| 09:05:20 INFO - PROCESS | 28858 | |0;dojo.html;3194.968050319497;3224.274538228898;3235.3529294141163;3252.804831710096;3278.803271803692;5518.448155184485;5558.249636299099;5569.777208911649;5572.192032155335;5586.10622300432;5557.055300598898;5625.40933202014;5630.09918413054;5659.4340565943385;5668.95124401986;5085.745712714359;5102.6428150029515;5138.100832354323;5140.974295128538;5172.456892026321;4972.527609877074;5165.457626949173;5200.7399630018535;5222.712750798704;5223.112070947948;4961.603071754251;5017.473165317644;5062.493750624926;5074.264231686403;5085.160948443502;5052.166392545226;5087.135187018197;5100.897982040376;5191.454897235812;5210.088234558937;4992.201247800334;5079.949200507985;5128.589712823003;5141.254518094893;5149.30484384607;5349.973250133763;5399.325084364455;5425.37608175061;5458.1266997280245;5499.615026948115;5824.30108386992;5847.912281315784;5849.03490923997;5955.076963070731;5957.1957785698805;5989.041753319448;5999.940000600025;6000.819975400745;6005.909911351333;6009.699515024231;5452.754626041838;5490.533304669068;5492.917606235909;5544.69504177266;5636.04187288162;6042.728077236535;6072.271327440735;6075.939240607626;6080.817575472654;6086.087086936906;5843;5848.707564621837;5853.853653658607;5856.707164641836;5858.06270996647;5715.771369145271;5756.654600723886;5876.059830427112;5931.347551769302;5936.762529498858;5921.171036054956;5922.8815423691285;5923.437273459014;5946.494547963385;5958.136070269873;5883.882322353615;5930.406959304035;5937.554683398763;5940.70296485174;5946.732397042057;4532.773361331921;4566.383538222364;4579.473360563511;4604.033153037833;4636.4899861015265;4139.151473947833;4140;4141.171765646883;4171.0615111599645;4185.079282557833;5671.319441667027;5671.773129074873;5679.858003549944;5680.375158732536;5701.230333904953;5755.223044888887;5826.912596311059;5830.300363956352;6005.249343832021;6014.849628759229;5619.43805619443;5661.575381846295;5681;5754.424557544296;5758.884822303614;5167.379914410295;5180.818671346485;5181.1969144782615;5182.378114626269;5182.507661772125;6157.353477884758;6252.249730032334;6267.341929097562;6273.278572963985;6273.811785646438;5770.884582308414;5830.679312637676;5904.025835737227;5956.285245770535;5956.851078723066;6267.059941008885;6273.905891411724;6274.247090349188;6275.090111933835;6280.591761535486;5873.794417195378;5876.941230587639;5890.410958904075;5910.674912879833;5929.080992446177;5780.624259423124;5826.533877289891;5914.201582786269;5923.111533270044;5953.404659534012;5912.408759124053;5916.733746981482;5934.376890426443;5935.703214839327;5939.851503712442;5847.532197424111;5857.1799948008165;5860.853478663067;5863.2670916135485;5875;5604.271444712162;5763.193152958505;5800;5806.593538452268;5819.185314055951;5759.222504961944;5759.2512973313205;5782.855428614318;5799.333076696234;5898.174255604133;5693.686847223276;5737.053386191232;5746.856328591819;5747.3103227612955;5773.364929857797 09:05:20 INFO - PROCESS | 28858 | |1;ext.html;574.8562859285179;590.4833270887972;620.7516993202719;628.5505863307737;648.5038945206915;34211.14472138198;36727;44541.10917781643;44841;44842.55157448425;45038.774806126006;45375.546244537545;45390.546094539044;45456.77271613645;45465;8310.501369917794;8991.59537820798;9138.177564019237;9138.406003609778;9143.13140251675;8374.120717324684;10708.089812365959;10943;10949.069329107035;11264.662060138167;10965.61620343288;11023.44882755863;11032.944835275812;11038.613648522303;11043.944780276088;10999.945000275027;11004.614838480615;11027.17296202784;11035.337879727242;11063.280886742335;10921.617743378943;10983.780324393467;10986.505607247693;10994;11028.44857757109;43816.561834381566;44141;44145.77927110376;44171;44190.779046104886;3596.892093237207;3617.5478065241846;3688.907777305562;3878.4182372644045;4362.847300344504;32101.1974700632;32532.512012319596;32734.6726532734;33270;33347;4303.289957157065;4369;4372.125574885035;4382.255016647177;4490.820367185309;337094;337665;338654;339180;339802;60901.39098608957;61118.0832287516;61266.693666531384;61323.69338153281;61500.69249653723;38787;39191.41212881809;39439.211215775525;42312.576874230865;42328.78835605802;18706.625867482573;18824.905875470537;18841.90579047096;18845.81154188468;18851.245950162112;13292.268925209206;13614.319284035759;14101.78847317291;14347.210903400204;14412.567622971328;19543.315983940494;19907.203711851653;19942.20231190736;20864.58270834605;21088.683669744965;7278.927210727825;7328.27785470414;7379.852402951911;7429.702811887572;7577.5453472791805;973.2068364283086;1014.9543270552842;1019.0573719309609;1076.8761592416815;1114.1142791480793;30459.75577501782;33384.332313353596;33465.83267083649;33536.496952545735;33595;1126.633844000703;1154.6189757380043;1161.7502237018912;1168.2815068732668;1180.2918249050501;16188.514344569681;17424.80205849244;17527.64944701099;17623.647527049387;17689.911550442164;509058;514506;515563;516481;517825;59343.70328148418;59955;59956.100658490155;59968;60065;1306.0922658752265;1349.3253373313344;1379.9793003104962;1382.5783136143573;1414.7877818327127;33126.33747324992;35233.647663523036;35552.288954220254;35557.64442355647;35628.64371356253;51660;52123.73938130437;52399.47600523946;52437.475625243256;52443;401446;405444;406190;406849;408143;30075.699243008166;31322.06033818989;32265.35469290648;32307;32649.836750816095;48490.35323239151;49200.50799491959;49707;49835.50164498309;50052.749736251084;42208.788956055025;42478.78760606177;42818;42824;44658.77670611756;41894.37158442565;42223.1555368897;42673;42955.35566966561;43901.56098438975;41131.7943410281;41146.794266029676;42414;42476.57523424726;42577.36133958055;35833.46249806203;38121;38374.424383634825;38925;38952.44190232359;28805.423891521634;29050;29050.12849614515;29250.853745731136;29301.85349073241 09:05:20 INFO - PROCESS | 28858 | |2;jquery.html;394.98753849138626;423.4385702721215;432.73819339299723;434.24007986024446;444.3379364746527;16355.182240887953;18827.435176944695;20179;20352.287669931557;20434.59130817383;18615;19909.502262443446;19910.900445497788;20101.698474522895;20276.29032983846;5657.66054036757;5893.469587737103;5895.734691938852;5899.174115623816;5902.586818922677;5530.419305972875;5718.914216286759;5739.827805165831;5740.339860915986;5742.942570574303;5640.605157638967;5719.7140142992685;5721.170430287606;5721.542276617861;5759.280089988752;5653.321601407837;5751.856203594902;5769.019266724646;5769.942300576982;5773.076307790754;5168.198929165986;5183.3261675981885;5216.113260745682;5223.13818219997;5225.451327610622;19661.213551457924;19707.80292197074;19735.605287894305;22896.54206915869;23541.17605883803;2694.205209463213;2809.8735556899987;2930.956035659467;3207.3104282579357;3271.06774569249;14707.338169782386;15278.38886444541;15474.22628868552;15481;15744.842551574453;1910.1308904448442;1999.6800511918168;2017.7074324223051;2047.0890453748088;2047.119738512439;87102;87810;87822.36532904023;87981;90632.54683726605;10209.183265338756;10852.72868178286;11251.268667536584;11636.94181529087;11651.708707282385;15028;15552.611184720472;15768.842311576738;15876.52370428889;16393.83606163947;8887;8964.058773828785;9012.549372531346;9201.837568171608;9280.396774209656;8777.605007774666;8835.425697329652;8988.370814042953;9240.5841737122;9257.166854983085;6957.686904089328;6967.407770339477;6986.650667466708;7035.542689725152;7120.71517139319;1991.6813309870352;1999.1903279171959;2020.4847763820133;2030.786767389403;2049.938501844947;950.933434659581;996.7209181429212;1021.581151727788;1047.502436342731;1055.3615062886997;19158.13788379526;19591.216351346073;19854.70217946732;19924.3026494072;20013.899930500254;973.1144658360857;1009.3792317724693;1017.5573625472942;1037.4138611684375;1059.645018918671;10782.436546700685;11176.664700059011;11346.035586975035;11372.260803047775;11407.372594507216;426625;427100;430422;512550;520329;95113;98290;101255.49372253091;102974.9999999985;104242;483.92741088836254;498.6534358620796;500.61202568009355;501.44590227799216;502.2892606961182;11115.333080015225;11364.54541818318;11896.22674526153;12250.693732656755;12329.136960412685;40872.75199864036;42688.35967460428;43349.56650433582;43791.12417751691;44608;62181;63040.684796575726;63265;63592.364076358645;63932.3606763945;8815.382923195315;8959.641614335354;9069.591868366071;9224.40041397307;9338.299627527827;11785.410729463321;12161.270323780602;13071.738565228452;13321.267330296927;13657.521986730417;6582.144321238208;7729.956455878384;11563.30620162793;12019.03847692165;12045.217060891015;11792.410379481164;11924.642260732191;12015.939920300343;12099.81850272229;12117.939410303245;12277.01783857307;12357.196782209128;12376.133670643329;12393.273479834423;12449.626511204679;8890.422122562013;9320.72037838866;9366.438013719198;9367.297452691211;9431.95284023603;10582.4708764563;11758.3532905691;11908.464119114487;11971.940140299243;12283.692907677023 09:05:20 INFO - PROCESS | 28858 | |3;mootools.html;1339.6316013096402;1498.4980031689383;1535.16333598189;1645.5084690894419;1807.7017292146797;7822.765317040491;8440.535770532626;8786.560671966408;8790.604422800974;8962.596683149257;8782.824343513143;8837.513936733492;8837.558122093886;8844.823103537941;8845.646174153042;2505.0355613089;2541.517111748771;2545.732698066704;2546.770790628843;2548.095426123727;2424.745401732819;2543.898244070235;2551.336652470362;2551.59662185798;2556.1053631229106;2488.6267059940974;2491.501699660066;2531.2659328794625;2532.3542496663326;2533.1260715053277;2453.116877923946;2469.8641574713383;2472.357187131359;2473.591857343536;2496.3384703053707;2469.6665950096685;2480.59070253408;2494.77547020769;2500.362407586072;2500.9749902500926;8587.914120858773;8664.220220180214;8689.348298877609;8740.125987401272;8788.692395766182;5429.619926605139;5568.638038527482;5582.720863956786;5589.944100558983;5612.214289999403;3584.605693373727;3616.963830361689;3666.6883314918264;3682.558093028827;3740.756850804716;1151.6545036489103;1161.5165461319384;1186.104491109207;1197.6407077876602;1206.6259459567561;12363.443645035995;12390.876091239063;12437.813432798515;12449.25304481725;12468;4727.285155582496;4785.473597904228;4821.349117869113;4866.6106711463;4884;9792.608295668093;9796.412215267106;9798.510074496247;9808.950955245178;9809.362391444532;5554.805581804699;5557.944420555824;5560.499555039985;5561.304836895388;5564.972175139179;4486.798094085774;4658.045100754337;4783.569478746929;5083.923741143886;5147.176451767699;4010.598940105966;4043.0903046814233;4139.296319625671;4150.79246037703;4169.124483858363;1255.209218192533;1281.135233717237;1287.7875150600235;1289.2844471318508;1294.5210272199347;933.1881263300971;1004.909558139771;1016.588281745894;1024.6772266735954;1029.6859457865423;6116.449519543263;6141.877162456726;6154.5384096192965;6364.586301890362;6469.5794773339585;499.9375078115236;507.3024591187118;514.4546780412775;520.945300743424;527.1776029394157;4118.75287482752;4360.32414975673;4366.432363792686;4372.125574885035;4382.517923028464;10734.57061717538;11016.614418495314;11085.113190944705;11104.666859994213;11111.666650000512;10796.838047429295;10913.345199288067;10990.780184396268;11140.888591114148;11146.888531114748;8097.554634495042;8145.837083258301;8202.343812494986;8205.425620206648;8511.957440212884;6035.215421995113;6056.182415373868;6059.787907423219;6154.5384096192065;6193.783217587363;10189.694309170736;10214.591416343561;10217;10236.48817559134;10238.027387398186;9288.396254243453;9378.155965963097;10048.296619236584;10122.8481572775;10351.378917264989;6873.690683919136;7429.034225550644;7478.252174782696;7612.581308028111;7717.421193410404;8682.262007729414;8727.65089396417;8841.16008979146;8847.42491738035;8848.646054157762;7718.073831140299;7812.414068944739;7854.528728276322;7955.164707705608;7992.5204487730925;7734.497257678233;7829.804254893445;7841.019872515935;7973.122956474667;8026.077001144943;7381.889271661037;7412.481126321321;7492.625368731433;7504;7521.623918804147;5274.736263186902;5528.9447105530035;5543.556515478671;5559.583031272751;5588.608797384144;5486.780528778804;5543.445655434424;5639.802606908738;5690.544756419394;5697.344805347272 09:05:20 INFO - PROCESS | 28858 | |4;prototype.html;337.976341656084;351.30617031363045;351.3394817742645;354.47714620934124;360.88090929993103;5241.240020197074;5264.631475796691;5275.393329767078;5287.312649355588;5303.681779093257;5219;5247.370315562129;5256.789728410859;5257.973710131444;5272.261883336336;3823.541175058997;3912.5109361329833;4018.7789671568125;4097.590240975908;4110.219058378913;4132.5040995080635;4177.603127702863;4179.916401671965;4180.602842729936;4189.706720529564;4171.770552619605;4172.666186705056;4173.4157217989505;4189.706720529564;4192.308269135589;3996.860109896169;4087.3255912774357;4160.521540022906;4174.833006679729;4178.9373159402785;3408.301298233856;3610.2598967211657;3701.6668499835146;3723.0320116769562;3731.1791405890663;5082.8475145745315;5088.516590923857;5096.184610462308;5111.233315002779;5115.386153661547;4649.1863923813535;4734.4081989751285;4759.833405830814;4766.713997160146;4772.546608072227;5486.862828429281;5507;5510.614257002011;5528.640638358494;5533.557315414757;4110.712250142491;4115.609017143366;4119.178362821477;4120.443740095079;4127.834886604533;5449.291592093003;5692.800751973702;5722.456366645162;5727.684977326246;5729.627574207663;4115.8765237042335;4123.814428350732;4126.938095928564;4141.627253547195;4145;4137.565555616678;4156.667466602725;4162.354835000519;4169.8957526062095;4176.895577610584;4485.170243504962;4674.696144750648;4725.8582242532775;4741.122892264942;4750.786214620351;5545.585875601776;5558.305211848519;5559.305086864142;5574.9721251393485;5580.581456390787;1403.515787053465;1409.8096756937682;1411.7458857405766;1412.8587141285996;1416.9433222670978;938.6573900526396;975.3855071305032;981.043482604455;985.1675334342613;995.4574756757585;5194.519561924833;5202.375714914235;5248.60635452335;5251.947480525222;5266.368035835724;5519;5536.446355364431;5544.861378465571;5551.333839939152;5556.777728890799;4987.301777751118;5076.18780995039;5148.326793792033;5189.636725429184;5205.505476979681;2867.555528893025;2895.9855200724282;2906.8401237931694;2914.3005678636973;2917.4748545262055;5715.599908006399;5720.42795720433;5724.742386592613;5757.337906140764;5757.481826635623;5329.241350354374;5483.75323110461;5489.286392768914;5500.42245564218;5537.667739935617;5773.8556536086935;5791.218185544982;5920.674362910081;5924.851878703067;5947.881042379128;5675.544697412676;5677.630953987978;5689.431056894443;5690.857728556819;5707.543396528351;5485.875395543983;5549.445055494418;5577.470140336662;5599.860003499945;5620.739970816998;5902.970485147547;5905.320888097924;5923.7926672566255;5926.288845338587;5926.762929482945;4034.9596504034585;4038.818253178555;4048.473698419187;4049.2913740094777;4058.492688413948;5445.455454454523;5616.775328986795;5654.7455364509515;5672.631278966854;5685.232493613309;5557.942701827607;5566.471185237396;5569.526590239713;5573.832785016456;5576.665400076008;5309.681419114865;5510.366307874646;5550.585495309553;5556.222128901876;5559.19391688211;5452.5365343946205;5457.672539647634;5464.726763661881;5499.697516636624;5502.944970550243;5533.5849811263515;5567.860803479945;5568.805091821929;5586.469285417878;5593.0848406115065;5302.257683924331;5372.167314066326;5427.05026620332;5440.945590544202;5445.40100588927 09:05:20 INFO - PROCESS | 28858 | |5;yui.html;776.0609662308607;800.8998875140608;850.9234168924797;855.4140413816535;898.7034278688034;14605.56183314501;15131.848681513182;15145.6970860583;15255.389784408624;15412.383504659814;14729.631759206026;14863.256837158126;15066.472673456432;15077.170755608437;15101;724.7499612633659;812.0417906869906;828.9005319361685;829.4857188543082;830.211299265697;746.9701211951516;836.8828364029041;862.211076864668;868.5787393114334;870.8780770692108;908.6501696846695;909.822584596004;910.5947853205327;910.8314961732068;911.0252030327553;758.4501236603473;908.3459908865673;909.9863502047475;910.085364208969;910.6448485090787;568.7128000359833;649.4252586461016;663.280340830195;664.3058004385429;665.8401983523968;14897.553073407815;14921.2539373032;14946.177960212184;14946.32741526623;14946.850531494654;13969.231692256923;14200.85799142016;14237.78643320351;14280.214588197645;14385.856141438557;12683.429245683967;12897.856467009868;12985.415656295394;13076.607701768962;13228.338583070905;4412.955870441287;4427.203103441379;4483.910321793546;4484.41702578666;4595.563421474954;65482.99999999953;65950.67024664894;65956.67021664909;66176;66216;11284.379359135162;11314.264572802742;11372.601958931564;11591.710207244887;11655.650330490098;20230.797692023185;20274.290399835936;20292.289769857984;20366;20402;7886.211378862068;7889.21107889218;8023.569607090735;8050.19498050202;8347.666093356198;4594.494605593382;4688.413948256468;4708.4820669726305;4723.362346083303;4861.1979023460735;3573.874914377984;3661.2860492204295;3691.53855768029;3700.7224458165747;3703.685186759156;642.8328634554956;656.8128083496181;661.2197606823897;663.6051549328142;664.5248647217263;626.2672672972633;635.8283263518824;658.7990662847782;673.373762400962;708.989365159523;9044.502552359552;9156;9184;9286.72139835806;9442.291828112919;698.8672152291049;713.1406654980735;723.605634928965;729.1359738709646;730.1129128109267;4385.232584297735;4537.546245375502;4603.631709463235;4689.859304220879;4694.1550520905885;87873.56063219771;89490;91588.54205728929;92229;94846;53895;58022;78232;80721;83223;71161;73101;74341.25658743452;74575;75646.6217668908;8943.597538110671;9143.13140251675;9192.402493837879;9212.815743685223;9342.906570934203;41209.38185927153;41864.3720344189;46051.76974115108;48181;49472.50527494679;58182;58500.707496462244;60582.69708651429;62187.37812621816;62260.37739622727;12505.124641275024;12518.436670349674;12578.748425031632;12733.681657958625;12955.870441295467;12755.234685918875;12833.165844220095;13116.868831311565;13318.866811332151;14019.859801401855;10541.631042913768;13290.534831280858;13820.861791381958;13912.234827084607;14257;12419.503219871465;13396.397162127923;13658.317084145554;13678.452861885413;13899.37452814646;13055.738885222432;13371.665708357368;13480.123791953492;13507.864921351054;13763.862361376257;10638.89361106379;10981.670549883516;11290.830637540608;11352.318860868374;11361.829572556253;11005.17461190398;11081.947215014561;11105.666829995112;11565.190436669352;11786.587469438871 09:05:20 INFO - PROCESS | 28858 | __end_tp_report 09:05:20 INFO - PROCESS | 28858 | __start_cc_report 09:05:20 INFO - PROCESS | 28858 | _x_x_mozilla_cycle_collect,893 09:05:20 INFO - PROCESS | 28858 | __end_cc_report 09:05:20 INFO - PROCESS | 28858 | __startTimestamp1483031120881__endTimestamp 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | ------- Summary: start ------- 09:05:20 INFO - PROCESS | 28858 | Number of tests: 6 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#0] dojo.html Cycles:165 Average:5542.14 Median:5758.88 stddev:614.23 (10.7%) stddev-sans-first:587.87 09:05:20 INFO - PROCESS | 28858 | Values: 3195.0 3224.3 3235.4 3252.8 3278.8 5518.4 5558.2 5569.8 5572.2 5586.1 5557.1 5625.4 5630.1 5659.4 5669.0 5085.7 5102.6 5138.1 5141.0 5172.5 4972.5 5165.5 5200.7 5222.7 5223.1 4961.6 5017.5 5062.5 5074.3 5085.2 5052.2 5087.1 5100.9 5191.5 5210.1 4992.2 5079.9 5128.6 5141.3 5149.3 5350.0 5399.3 5425.4 5458.1 5499.6 5824.3 5847.9 5849.0 5955.1 5957.2 5989.0 5999.9 6000.8 6005.9 6009.7 5452.8 5490.5 5492.9 5544.7 5636.0 6042.7 6072.3 6075.9 6080.8 6086.1 5843.0 5848.7 5853.9 5856.7 5858.1 5715.8 5756.7 5876.1 5931.3 5936.8 5921.2 5922.9 5923.4 5946.5 5958.1 5883.9 5930.4 5937.6 5940.7 5946.7 4532.8 4566.4 4579.5 4604.0 4636.5 4139.2 4140.0 4141.2 4171.1 4185.1 5671.3 5671.8 5679.9 5680.4 5701.2 5755.2 5826.9 5830.3 6005.2 6014.8 5619.4 5661.6 5681.0 5754.4 5758.9 5167.4 5180.8 5181.2 5182.4 5182.5 6157.4 6252.2 6267.3 6273.3 6273.8 5770.9 5830.7 5904.0 5956.3 5956.9 6267.1 6273.9 6274.2 6275.1 6280.6 5873.8 5876.9 5890.4 5910.7 5929.1 5780.6 5826.5 5914.2 5923.1 5953.4 5912.4 5916.7 5934.4 5935.7 5939.9 5847.5 5857.2 5860.9 5863.3 5875.0 5604.3 5763.2 5800.0 5806.6 5819.2 5759.2 5759.3 5782.9 5799.3 5898.2 5693.7 5737.1 5746.9 5747.3 5773.4 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#1] ext.html Cycles:180 Average:58949.70 Median:21126.42 stddev:112461.30 (532.3%) stddev-sans-first:112691.38 09:05:20 INFO - PROCESS | 28858 | Values: 574.9 590.5 620.8 628.6 648.5 34211.1 36727.0 44541.1 44841.0 44842.6 45038.8 45375.5 45390.5 45456.8 45465.0 8310.5 8991.6 9138.2 9138.4 9143.1 8374.1 10708.1 10943.0 10949.1 11264.7 10965.6 11023.4 11032.9 11038.6 11043.9 10999.9 11004.6 11027.2 11035.3 11063.3 10921.6 10983.8 10986.5 10994.0 11028.4 43816.6 44141.0 44145.8 44171.0 44190.8 3596.9 3617.5 3688.9 3878.4 4362.8 32101.2 32532.5 32734.7 33270.0 33347.0 4303.3 4369.0 4372.1 4382.3 4490.8 337094.0 337665.0 338654.0 339180.0 339802.0 60901.4 61118.1 61266.7 61323.7 61500.7 38787.0 39191.4 39439.2 42312.6 42328.8 18706.6 18824.9 18841.9 18845.8 18851.2 13292.3 13614.3 14101.8 14347.2 14412.6 19543.3 19907.2 19942.2 20864.6 21088.7 7278.9 7328.3 7379.9 7429.7 7577.5 973.2 1015.0 1019.1 1076.9 1114.1 30459.8 33384.3 33465.8 33536.5 33595.0 1126.6 1154.6 1161.8 1168.3 1180.3 16188.5 17424.8 17527.6 17623.6 17689.9 509058.0 514506.0 515563.0 516481.0 517825.0 59343.7 59955.0 59956.1 59968.0 60065.0 1306.1 1349.3 1380.0 1382.6 1414.8 33126.3 35233.6 35552.3 35557.6 35628.6 51660.0 52123.7 52399.5 52437.5 52443.0 401446.0 405444.0 406190.0 406849.0 408143.0 30075.7 31322.1 32265.4 32307.0 32649.8 48490.4 49200.5 49707.0 49835.5 50052.7 42208.8 42478.8 42818.0 42824.0 44658.8 41894.4 42223.2 42673.0 42955.4 43901.6 41131.8 41146.8 42414.0 42476.6 42577.4 35833.5 38121.0 38374.4 38925.0 38952.4 28805.4 29050.0 29050.1 29250.9 29301.9 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#2] jquery.html Cycles:180 Average:28994.10 Median:3069.13 stddev:77284.48 (2518.1%) stddev-sans-first:77471.45 09:05:20 INFO - PROCESS | 28858 | Values: 395.0 423.4 432.7 434.2 444.3 16355.2 18827.4 20179.0 20352.3 20434.6 18615.0 19909.5 19910.9 20101.7 20276.3 5657.7 5893.5 5895.7 5899.2 5902.6 5530.4 5718.9 5739.8 5740.3 5742.9 5640.6 5719.7 5721.2 5721.5 5759.3 5653.3 5751.9 5769.0 5769.9 5773.1 5168.2 5183.3 5216.1 5223.1 5225.5 19661.2 19707.8 19735.6 22896.5 23541.2 2694.2 2809.9 2931.0 3207.3 3271.1 14707.3 15278.4 15474.2 15481.0 15744.8 1910.1 1999.7 2017.7 2047.1 2047.1 87102.0 87810.0 87822.4 87981.0 90632.5 10209.2 10852.7 11251.3 11636.9 11651.7 15028.0 15552.6 15768.8 15876.5 16393.8 8887.0 8964.1 9012.5 9201.8 9280.4 8777.6 8835.4 8988.4 9240.6 9257.2 6957.7 6967.4 6986.7 7035.5 7120.7 1991.7 1999.2 2020.5 2030.8 2049.9 950.9 996.7 1021.6 1047.5 1055.4 19158.1 19591.2 19854.7 19924.3 20013.9 973.1 1009.4 1017.6 1037.4 1059.6 10782.4 11176.7 11346.0 11372.3 11407.4 426625.0 427100.0 430422.0 512550.0 520329.0 95113.0 98290.0 101255.5 102975.0 104242.0 483.9 498.7 500.6 501.4 502.3 11115.3 11364.5 11896.2 12250.7 12329.1 40872.8 42688.4 43349.6 43791.1 44608.0 62181.0 63040.7 63265.0 63592.4 63932.4 8815.4 8959.6 9069.6 9224.4 9338.3 11785.4 12161.3 13071.7 13321.3 13657.5 6582.1 7730.0 11563.3 12019.0 12045.2 11792.4 11924.6 12015.9 12099.8 12117.9 12277.0 12357.2 12376.1 12393.3 12449.6 8890.4 9320.7 9366.4 9367.3 9432.0 10582.5 11758.4 11908.5 11971.9 12283.7 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#3] mootools.html Cycles:180 Average:5900.48 Median:503.62 stddev:3262.88 (647.9%) stddev-sans-first:3254.02 09:05:20 INFO - PROCESS | 28858 | Values: 1339.6 1498.5 1535.2 1645.5 1807.7 7822.8 8440.5 8786.6 8790.6 8962.6 8782.8 8837.5 8837.6 8844.8 8845.6 2505.0 2541.5 2545.7 2546.8 2548.1 2424.7 2543.9 2551.3 2551.6 2556.1 2488.6 2491.5 2531.3 2532.4 2533.1 2453.1 2469.9 2472.4 2473.6 2496.3 2469.7 2480.6 2494.8 2500.4 2501.0 8587.9 8664.2 8689.3 8740.1 8788.7 5429.6 5568.6 5582.7 5589.9 5612.2 3584.6 3617.0 3666.7 3682.6 3740.8 1151.7 1161.5 1186.1 1197.6 1206.6 12363.4 12390.9 12437.8 12449.3 12468.0 4727.3 4785.5 4821.3 4866.6 4884.0 9792.6 9796.4 9798.5 9809.0 9809.4 5554.8 5557.9 5560.5 5561.3 5565.0 4486.8 4658.0 4783.6 5083.9 5147.2 4010.6 4043.1 4139.3 4150.8 4169.1 1255.2 1281.1 1287.8 1289.3 1294.5 933.2 1004.9 1016.6 1024.7 1029.7 6116.4 6141.9 6154.5 6364.6 6469.6 499.9 507.3 514.5 520.9 527.2 4118.8 4360.3 4366.4 4372.1 4382.5 10734.6 11016.6 11085.1 11104.7 11111.7 10796.8 10913.3 10990.8 11140.9 11146.9 8097.6 8145.8 8202.3 8205.4 8512.0 6035.2 6056.2 6059.8 6154.5 6193.8 10189.7 10214.6 10217.0 10236.5 10238.0 9288.4 9378.2 10048.3 10122.8 10351.4 6873.7 7429.0 7478.3 7612.6 7717.4 8682.3 8727.7 8841.2 8847.4 8848.6 7718.1 7812.4 7854.5 7955.2 7992.5 7734.5 7829.8 7841.0 7973.1 8026.1 7381.9 7412.5 7492.6 7504.0 7521.6 5274.7 5528.9 5543.6 5559.6 5588.6 5486.8 5543.4 5639.8 5690.5 5697.3 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#4] prototype.html Cycles:180 Average:4645.48 Median:5269.31 stddev:1351.13 (25.6%) stddev-sans-first:1315.67 09:05:20 INFO - PROCESS | 28858 | Values: 338.0 351.3 351.3 354.5 360.9 5241.2 5264.6 5275.4 5287.3 5303.7 5219.0 5247.4 5256.8 5258.0 5272.3 3823.5 3912.5 4018.8 4097.6 4110.2 4132.5 4177.6 4179.9 4180.6 4189.7 4171.8 4172.7 4173.4 4189.7 4192.3 3996.9 4087.3 4160.5 4174.8 4178.9 3408.3 3610.3 3701.7 3723.0 3731.2 5082.8 5088.5 5096.2 5111.2 5115.4 4649.2 4734.4 4759.8 4766.7 4772.5 5486.9 5507.0 5510.6 5528.6 5533.6 4110.7 4115.6 4119.2 4120.4 4127.8 5449.3 5692.8 5722.5 5727.7 5729.6 4115.9 4123.8 4126.9 4141.6 4145.0 4137.6 4156.7 4162.4 4169.9 4176.9 4485.2 4674.7 4725.9 4741.1 4750.8 5545.6 5558.3 5559.3 5575.0 5580.6 1403.5 1409.8 1411.7 1412.9 1416.9 938.7 975.4 981.0 985.2 995.5 5194.5 5202.4 5248.6 5251.9 5266.4 5519.0 5536.4 5544.9 5551.3 5556.8 4987.3 5076.2 5148.3 5189.6 5205.5 2867.6 2896.0 2906.8 2914.3 2917.5 5715.6 5720.4 5724.7 5757.3 5757.5 5329.2 5483.8 5489.3 5500.4 5537.7 5773.9 5791.2 5920.7 5924.9 5947.9 5675.5 5677.6 5689.4 5690.9 5707.5 5485.9 5549.4 5577.5 5599.9 5620.7 5903.0 5905.3 5923.8 5926.3 5926.8 4035.0 4038.8 4048.5 4049.3 4058.5 5445.5 5616.8 5654.7 5672.6 5685.2 5557.9 5566.5 5569.5 5573.8 5576.7 5309.7 5510.4 5550.6 5556.2 5559.2 5452.5 5457.7 5464.7 5499.7 5502.9 5533.6 5567.9 5568.8 5586.5 5593.1 5302.3 5372.2 5427.1 5440.9 5445.4 09:05:20 INFO - PROCESS | 28858 | 09:05:20 INFO - PROCESS | 28858 | [#5] yui.html Cycles:180 Average:18070.34 Median:4715.92 stddev:23794.89 (504.6%) stddev-sans-first:23826.20 09:05:20 INFO - PROCESS | 28858 | Values: 776.1 800.9 850.9 855.4 898.7 14605.6 15131.8 15145.7 15255.4 15412.4 14729.6 14863.3 15066.5 15077.2 15101.0 724.7 812.0 828.9 829.5 830.2 747.0 836.9 862.2 868.6 870.9 908.7 909.8 910.6 910.8 911.0 758.5 908.3 910.0 910.1 910.6 568.7 649.4 663.3 664.3 665.8 14897.6 14921.3 14946.2 14946.3 14946.9 13969.2 14200.9 14237.8 14280.2 14385.9 12683.4 12897.9 12985.4 13076.6 13228.3 4413.0 4427.2 4483.9 4484.4 4595.6 65483.0 65950.7 65956.7 66176.0 66216.0 11284.4 11314.3 11372.6 11591.7 11655.7 20230.8 20274.3 20292.3 20366.0 20402.0 7886.2 7889.2 8023.6 8050.2 8347.7 4594.5 4688.4 4708.5 4723.4 4861.2 3573.9 3661.3 3691.5 3700.7 3703.7 642.8 656.8 661.2 663.6 664.5 626.3 635.8 658.8 673.4 709.0 9044.5 9156.0 9184.0 9286.7 9442.3 698.9 713.1 723.6 729.1 730.1 4385.2 4537.5 4603.6 4689.9 4694.2 87873.6 89490.0 91588.5 92229.0 94846.0 53895.0 58022.0 78232.0 80721.0 83223.0 71161.0 73101.0 74341.3 74575.0 75646.6 8943.6 9143.1 9192.4 9212.8 9342.9 41209.4 41864.4 46051.8 48181.0 49472.5 58182.0 58500.7 60582.7 62187.4 62260.4 12505.1 12518.4 12578.7 12733.7 12955.9 12755.2 12833.2 13116.9 13318.9 14019.9 10541.6 13290.5 13820.9 13912.2 14257.0 12419.5 13396.4 13658.3 13678.5 13899.4 13055.7 13371.7 13480.1 13507.9 13763.9 10638.9 10981.7 11290.8 11352.3 11361.8 11005.2 11081.9 11105.7 11565.2 11786.6 09:05:20 INFO - PROCESS | 28858 | -------- Summary: end -------- 09:05:20 INFO - PROCESS | 28858 | 09:05:21 INFO - TEST-INFO | 28858: exit 0 09:05:21 INFO - TEST-OK | dromaeo_css | took 1115547ms 09:05:21 INFO - TEST-START | kraken 09:05:21 INFO - operating with platform_type : linux_ 09:05:21 INFO - Initialising browser for kraken test... 09:05:21 INFO - TEST-INFO | started process 29172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJfqjw/profile http://localhost:42341/getInfo.html) 09:05:26 INFO - PROCESS | 29172 | __metrics Screen width/height:1600/1200 09:05:26 INFO - PROCESS | 29172 | colorDepth:24 09:05:26 INFO - PROCESS | 29172 | Browser inner width/height: 1024/697 09:05:26 INFO - PROCESS | 29172 | __metrics 09:05:26 INFO - PROCESS | 29172 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:05:26 INFO - PROCESS | 29172 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:05:27 INFO - PROCESS | 29172 | console.error: PushService: 09:05:27 INFO - PROCESS | 29172 | stateChangeProcessEnqueue: Error transitioning state 09:05:27 INFO - PROCESS | 29172 | UnknownError 09:05:28 INFO - TEST-INFO | 29172: exit 0 09:05:28 INFO - Browser initialized. 09:05:28 INFO - Running cycle 1/1 for kraken test... 09:05:28 INFO - TEST-INFO | started process 29268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJfqjw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:40 INFO - PROCESS | 29268 | RSS: Main: 148361216 09:05:40 INFO - PROCESS | 29268 | 09:05:42 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42341/tests/kraken/driver.html?testName=audio-beat-detection) 09:05:43 INFO - PROCESS | 29268 | RSS: Main: 188919808 09:05:43 INFO - PROCESS | 29268 | 09:05:45 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42341/tests/kraken/driver.html?testName=audio-dft) 09:05:46 INFO - PROCESS | 29268 | RSS: Main: 178212864 09:05:46 INFO - PROCESS | 29268 | 09:05:49 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42341/tests/kraken/driver.html?testName=audio-fft) 09:05:49 INFO - PROCESS | 29268 | RSS: Main: 270118912 09:05:49 INFO - PROCESS | 29268 | 09:05:51 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42341/tests/kraken/driver.html?testName=audio-oscillator) 09:05:52 INFO - PROCESS | 29268 | RSS: Main: 176447488 09:05:52 INFO - PROCESS | 29268 | 09:05:54 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42341/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:05:55 INFO - PROCESS | 29268 | RSS: Main: 178675712 09:05:55 INFO - PROCESS | 29268 | 09:05:58 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42341/tests/kraken/driver.html?testName=imaging-darkroom) 09:05:58 INFO - PROCESS | 29268 | RSS: Main: 217247744 09:05:58 INFO - PROCESS | 29268 | 09:06:02 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42341/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:02 INFO - PROCESS | 29268 | RSS: Main: 216973312 09:06:02 INFO - PROCESS | 29268 | 09:06:05 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42341/tests/kraken/driver.html?testName=json-parse-financial) 09:06:05 INFO - PROCESS | 29268 | RSS: Main: 215408640 09:06:05 INFO - PROCESS | 29268 | 09:06:07 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42341/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:07 INFO - PROCESS | 29268 | RSS: Main: 177917952 09:06:07 INFO - PROCESS | 29268 | 09:06:09 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:10 INFO - PROCESS | 29268 | RSS: Main: 185061376 09:06:10 INFO - PROCESS | 29268 | 09:06:12 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:12 INFO - PROCESS | 29268 | RSS: Main: 190464000 09:06:12 INFO - PROCESS | 29268 | 09:06:15 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:16 INFO - PROCESS | 29268 | RSS: Main: 198897664 09:06:16 INFO - PROCESS | 29268 | 09:06:18 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:19 INFO - PROCESS | 29268 | RSS: Main: 195813376 09:06:19 INFO - PROCESS | 29268 | 09:06:20 INFO - PROCESS | 29268 | Cycle 1(1): loaded http://localhost:42341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42341/tests/kraken/driver.html?testName=ai-astar) 09:06:21 INFO - PROCESS | 29268 | RSS: Main: 245366784 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | __start_tp_report 09:06:21 INFO - PROCESS | 29268 | _x_x_mozilla_page_load 09:06:21 INFO - PROCESS | 29268 | _x_x_mozilla_page_load_details 09:06:21 INFO - PROCESS | 29268 | |i|pagename|runs| 09:06:21 INFO - PROCESS | 29268 | |0;ai-astar;103;113;111;111;112;110;111;111;113;110 09:06:21 INFO - PROCESS | 29268 | |1;audio-beat-detection;151;117;118;146;118;121;118;118;148;119 09:06:21 INFO - PROCESS | 29268 | |2;audio-dft;161;159;160;161;160;160;160;159;159;159 09:06:21 INFO - PROCESS | 29268 | |3;audio-fft;75;74;75;108;75;76;76;75;98;75 09:06:21 INFO - PROCESS | 29268 | |4;audio-oscillator;82;81;109;83;82;82;98;82;81;110 09:06:21 INFO - PROCESS | 29268 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;116;117 09:06:21 INFO - PROCESS | 29268 | |6;imaging-darkroom;146;146;146;146;146;145;145;146;146;145 09:06:21 INFO - PROCESS | 29268 | |7;imaging-desaturate;84;85;84;85;85;85;85;85;85;85 09:06:21 INFO - PROCESS | 29268 | |8;json-parse-financial;52;51;52;53;51;51;53;50;51;51 09:06:21 INFO - PROCESS | 29268 | |9;json-stringify-tinderbox;60;60;60;59;60;66;60;60;61;60 09:06:21 INFO - PROCESS | 29268 | |10;stanford-crypto-aes;74;82;74;75;82;74;75;81;75;88 09:06:21 INFO - PROCESS | 29268 | |11;stanford-crypto-ccm;125;128;130;124;127;130;130;119;163;131 09:06:21 INFO - PROCESS | 29268 | |12;stanford-crypto-pbkdf2;161;155;149;155;153;150;153;159;152;154 09:06:21 INFO - PROCESS | 29268 | |13;stanford-crypto-sha256-iterative;63;63;62;62;62;62;64;60;59;60 09:06:21 INFO - PROCESS | 29268 | __end_tp_report 09:06:21 INFO - PROCESS | 29268 | __start_cc_report 09:06:21 INFO - PROCESS | 29268 | _x_x_mozilla_cycle_collect,1618 09:06:21 INFO - PROCESS | 29268 | __end_cc_report 09:06:21 INFO - PROCESS | 29268 | __startTimestamp1483031181207__endTimestamp 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | ------- Summary: start ------- 09:06:21 INFO - PROCESS | 29268 | Number of tests: 14 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:2.84 (2.6%) stddev-sans-first:1.12 09:06:21 INFO - PROCESS | 29268 | Values: 103.0 113.0 111.0 111.0 112.0 110.0 111.0 111.0 113.0 110.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:120.00 stddev:14.53 (12.1%) stddev-sans-first:12.66 09:06:21 INFO - PROCESS | 29268 | Values: 151.0 117.0 118.0 146.0 118.0 121.0 118.0 118.0 148.0 119.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 09:06:21 INFO - PROCESS | 29268 | Values: 161.0 159.0 160.0 161.0 160.0 160.0 160.0 159.0 159.0 159.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#3] audio-fft Cycles:10 Average:80.70 Median:75.00 stddev:12.00 (16.0%) stddev-sans-first:12.55 09:06:21 INFO - PROCESS | 29268 | Values: 75.0 74.0 75.0 108.0 75.0 76.0 76.0 75.0 98.0 75.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:82.00 stddev:11.94 (14.6%) stddev-sans-first:12.39 09:06:21 INFO - PROCESS | 29268 | Values: 82.0 81.0 109.0 83.0 82.0 82.0 98.0 82.0 81.0 110.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:06:21 INFO - PROCESS | 29268 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:06:21 INFO - PROCESS | 29268 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.42 (0.5%) stddev-sans-first:0.33 09:06:21 INFO - PROCESS | 29268 | Values: 84.0 85.0 84.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 09:06:21 INFO - PROCESS | 29268 | Values: 52.0 51.0 52.0 53.0 51.0 51.0 53.0 50.0 51.0 51.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:60.00 stddev:1.96 (3.3%) stddev-sans-first:2.06 09:06:21 INFO - PROCESS | 29268 | Values: 60.0 60.0 60.0 59.0 60.0 66.0 60.0 60.0 61.0 60.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:4.90 (6.3%) stddev-sans-first:4.98 09:06:21 INFO - PROCESS | 29268 | Values: 74.0 82.0 74.0 75.0 82.0 74.0 75.0 81.0 75.0 88.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#11] stanford-crypto-ccm Cycles:10 Average:130.70 Median:130.00 stddev:11.93 (9.2%) stddev-sans-first:12.47 09:06:21 INFO - PROCESS | 29268 | Values: 125.0 128.0 130.0 124.0 127.0 130.0 130.0 119.0 163.0 131.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:154.50 stddev:3.70 (2.4%) stddev-sans-first:2.96 09:06:21 INFO - PROCESS | 29268 | Values: 161.0 155.0 149.0 155.0 153.0 150.0 153.0 159.0 152.0 154.0 09:06:21 INFO - PROCESS | 29268 | 09:06:21 INFO - PROCESS | 29268 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.57 (2.5%) stddev-sans-first:1.59 09:06:21 INFO - PROCESS | 29268 | Values: 63.0 63.0 62.0 62.0 62.0 62.0 64.0 60.0 59.0 60.0 09:06:21 INFO - PROCESS | 29268 | -------- Summary: end -------- 09:06:21 INFO - PROCESS | 29268 | 09:06:22 INFO - TEST-INFO | 29268: exit 0 09:06:22 INFO - TEST-OK | kraken | took 60601ms 09:06:22 INFO - SUITE-END | took 1176s 09:06:22 INFO - Completed test suite (00:19:36) 09:06:22 INFO - javascript benchmark 09:06:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.968050319497, 3224.274538228898, 3235.3529294141163, 3252.804831710096, 3278.803271803692, 5518.448155184485, 5558.249636299099, 5569.777208911649, 5572.192032155335, 5586.10622300432, 5557.055300598898, 5625.40933202014, 5630.09918413054, 5659.4340565943385, 5668.95124401986, 5085.745712714359, 5102.6428150029515, 5138.100832354323, 5140.974295128538, 5172.456892026321, 4972.527609877074, 5165.457626949173, 5200.7399630018535, 5222.712750798704, 5223.112070947948, 4961.603071754251, 5017.473165317644, 5062.493750624926, 5074.264231686403, 5085.160948443502, 5052.166392545226, 5087.135187018197, 5100.897982040376, 5191.454897235812, 5210.088234558937, 4992.201247800334, 5079.949200507985, 5128.589712823003, 5141.254518094893, 5149.30484384607, 5349.973250133763, 5399.325084364455, 5425.37608175061, 5458.1266997280245, 5499.615026948115, 5824.30108386992, 5847.912281315784, 5849.03490923997, 5955.076963070731, 5957.1957785698805, 5989.041753319448, 5999.940000600025, 6000.819975400745, 6005.909911351333, 6009.699515024231, 5452.754626041838, 5490.533304669068, 5492.917606235909, 5544.69504177266, 5636.04187288162, 6042.728077236535, 6072.271327440735, 6075.939240607626, 6080.817575472654, 6086.087086936906, 5843.0, 5848.707564621837, 5853.853653658607, 5856.707164641836, 5858.06270996647, 5715.771369145271, 5756.654600723886, 5876.059830427112, 5931.347551769302, 5936.762529498858, 5921.171036054956, 5922.8815423691285, 5923.437273459014, 5946.494547963385, 5958.136070269873, 5883.882322353615, 5930.406959304035, 5937.554683398763, 5940.70296485174, 5946.732397042057, 4532.773361331921, 4566.383538222364, 4579.473360563511, 4604.033153037833, 4636.4899861015265, 4139.151473947833, 4140.0, 4141.171765646883, 4171.0615111599645, 4185.079282557833, 5671.319441667027, 5671.773129074873, 5679.858003549944, 5680.375158732536, 5701.230333904953, 5755.223044888887, 5826.912596311059, 5830.300363956352, 6005.249343832021, 6014.849628759229, 5619.43805619443, 5661.575381846295, 5681.0, 5754.424557544296, 5758.884822303614, 5167.379914410295, 5180.818671346485, 5181.1969144782615, 5182.378114626269, 5182.507661772125, 6157.353477884758, 6252.249730032334, 6267.341929097562, 6273.278572963985, 6273.811785646438, 5770.884582308414, 5830.679312637676, 5904.025835737227, 5956.285245770535, 5956.851078723066, 6267.059941008885, 6273.905891411724, 6274.247090349188, 6275.090111933835, 6280.591761535486, 5873.794417195378, 5876.941230587639, 5890.410958904075, 5910.674912879833, 5929.080992446177, 5780.624259423124, 5826.533877289891, 5914.201582786269, 5923.111533270044, 5953.404659534012, 5912.408759124053, 5916.733746981482, 5934.376890426443, 5935.703214839327, 5939.851503712442, 5847.532197424111, 5857.1799948008165, 5860.853478663067, 5863.2670916135485, 5875.0, 5604.271444712162, 5763.193152958505, 5800.0, 5806.593538452268, 5819.185314055951, 5759.222504961944, 5759.2512973313205, 5782.855428614318, 5799.333076696234, 5898.174255604133, 5693.686847223276, 5737.053386191232, 5746.856328591819, 5747.3103227612955, 5773.364929857797], "value": 5501.632810086712, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.8562859285179, 590.4833270887972, 620.7516993202719, 628.5505863307737, 648.5038945206915, 34211.14472138198, 36727.0, 44541.10917781643, 44841.0, 44842.55157448425, 45038.774806126006, 45375.546244537545, 45390.546094539044, 45456.77271613645, 45465.0, 8310.501369917794, 8991.59537820798, 9138.177564019237, 9138.406003609778, 9143.13140251675, 8374.120717324684, 10708.089812365959, 10943.0, 10949.069329107035, 11264.662060138167, 10965.61620343288, 11023.44882755863, 11032.944835275812, 11038.613648522303, 11043.944780276088, 10999.945000275027, 11004.614838480615, 11027.17296202784, 11035.337879727242, 11063.280886742335, 10921.617743378943, 10983.780324393467, 10986.505607247693, 10994.0, 11028.44857757109, 43816.561834381566, 44141.0, 44145.77927110376, 44171.0, 44190.779046104886, 3596.892093237207, 3617.5478065241846, 3688.907777305562, 3878.4182372644045, 4362.847300344504, 32101.1974700632, 32532.512012319596, 32734.6726532734, 33270.0, 33347.0, 4303.289957157065, 4369.0, 4372.125574885035, 4382.255016647177, 4490.820367185309, 337094.0, 337665.0, 338654.0, 339180.0, 339802.0, 60901.39098608957, 61118.0832287516, 61266.693666531384, 61323.69338153281, 61500.69249653723, 38787.0, 39191.41212881809, 39439.211215775525, 42312.576874230865, 42328.78835605802, 18706.625867482573, 18824.905875470537, 18841.90579047096, 18845.81154188468, 18851.245950162112, 13292.268925209206, 13614.319284035759, 14101.78847317291, 14347.210903400204, 14412.567622971328, 19543.315983940494, 19907.203711851653, 19942.20231190736, 20864.58270834605, 21088.683669744965, 7278.927210727825, 7328.27785470414, 7379.852402951911, 7429.702811887572, 7577.5453472791805, 973.2068364283086, 1014.9543270552842, 1019.0573719309609, 1076.8761592416815, 1114.1142791480793, 30459.75577501782, 33384.332313353596, 33465.83267083649, 33536.496952545735, 33595.0, 1126.633844000703, 1154.6189757380043, 1161.7502237018912, 1168.2815068732668, 1180.2918249050501, 16188.514344569681, 17424.80205849244, 17527.64944701099, 17623.647527049387, 17689.911550442164, 509058.0, 514506.0, 515563.0, 516481.0, 517825.0, 59343.70328148418, 59955.0, 59956.100658490155, 59968.0, 60065.0, 1306.0922658752265, 1349.3253373313344, 1379.9793003104962, 1382.5783136143573, 1414.7877818327127, 33126.33747324992, 35233.647663523036, 35552.288954220254, 35557.64442355647, 35628.64371356253, 51660.0, 52123.73938130437, 52399.47600523946, 52437.475625243256, 52443.0, 401446.0, 405444.0, 406190.0, 406849.0, 408143.0, 30075.699243008166, 31322.06033818989, 32265.35469290648, 32307.0, 32649.836750816095, 48490.35323239151, 49200.50799491959, 49707.0, 49835.50164498309, 50052.749736251084, 42208.788956055025, 42478.78760606177, 42818.0, 42824.0, 44658.77670611756, 41894.37158442565, 42223.1555368897, 42673.0, 42955.35566966561, 43901.56098438975, 41131.7943410281, 41146.794266029676, 42414.0, 42476.57523424726, 42577.36133958055, 35833.46249806203, 38121.0, 38374.424383634825, 38925.0, 38952.44190232359, 28805.423891521634, 29050.0, 29050.12849614515, 29250.853745731136, 29301.85349073241], "value": 20885.53653394983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.98753849138626, 423.4385702721215, 432.73819339299723, 434.24007986024446, 444.3379364746527, 16355.182240887953, 18827.435176944695, 20179.0, 20352.287669931557, 20434.59130817383, 18615.0, 19909.502262443446, 19910.900445497788, 20101.698474522895, 20276.29032983846, 5657.66054036757, 5893.469587737103, 5895.734691938852, 5899.174115623816, 5902.586818922677, 5530.419305972875, 5718.914216286759, 5739.827805165831, 5740.339860915986, 5742.942570574303, 5640.605157638967, 5719.7140142992685, 5721.170430287606, 5721.542276617861, 5759.280089988752, 5653.321601407837, 5751.856203594902, 5769.019266724646, 5769.942300576982, 5773.076307790754, 5168.198929165986, 5183.3261675981885, 5216.113260745682, 5223.13818219997, 5225.451327610622, 19661.213551457924, 19707.80292197074, 19735.605287894305, 22896.54206915869, 23541.17605883803, 2694.205209463213, 2809.8735556899987, 2930.956035659467, 3207.3104282579357, 3271.06774569249, 14707.338169782386, 15278.38886444541, 15474.22628868552, 15481.0, 15744.842551574453, 1910.1308904448442, 1999.6800511918168, 2017.7074324223051, 2047.0890453748088, 2047.119738512439, 87102.0, 87810.0, 87822.36532904023, 87981.0, 90632.54683726605, 10209.183265338756, 10852.72868178286, 11251.268667536584, 11636.94181529087, 11651.708707282385, 15028.0, 15552.611184720472, 15768.842311576738, 15876.52370428889, 16393.83606163947, 8887.0, 8964.058773828785, 9012.549372531346, 9201.837568171608, 9280.396774209656, 8777.605007774666, 8835.425697329652, 8988.370814042953, 9240.5841737122, 9257.166854983085, 6957.686904089328, 6967.407770339477, 6986.650667466708, 7035.542689725152, 7120.71517139319, 1991.6813309870352, 1999.1903279171959, 2020.4847763820133, 2030.786767389403, 2049.938501844947, 950.933434659581, 996.7209181429212, 1021.581151727788, 1047.502436342731, 1055.3615062886997, 19158.13788379526, 19591.216351346073, 19854.70217946732, 19924.3026494072, 20013.899930500254, 973.1144658360857, 1009.3792317724693, 1017.5573625472942, 1037.4138611684375, 1059.645018918671, 10782.436546700685, 11176.664700059011, 11346.035586975035, 11372.260803047775, 11407.372594507216, 426625.0, 427100.0, 430422.0, 512550.0, 520329.0, 95113.0, 98290.0, 101255.49372253091, 102974.9999999985, 104242.0, 483.92741088836254, 498.6534358620796, 500.61202568009355, 501.44590227799216, 502.2892606961182, 11115.333080015225, 11364.54541818318, 11896.22674526153, 12250.693732656755, 12329.136960412685, 40872.75199864036, 42688.35967460428, 43349.56650433582, 43791.12417751691, 44608.0, 62181.0, 63040.684796575726, 63265.0, 63592.364076358645, 63932.3606763945, 8815.382923195315, 8959.641614335354, 9069.591868366071, 9224.40041397307, 9338.299627527827, 11785.410729463321, 12161.270323780602, 13071.738565228452, 13321.267330296927, 13657.521986730417, 6582.144321238208, 7729.956455878384, 11563.30620162793, 12019.03847692165, 12045.217060891015, 11792.410379481164, 11924.642260732191, 12015.939920300343, 12099.81850272229, 12117.939410303245, 12277.01783857307, 12357.196782209128, 12376.133670643329, 12393.273479834423, 12449.626511204679, 8890.422122562013, 9320.72037838866, 9366.438013719198, 9367.297452691211, 9431.95284023603, 10582.4708764563, 11758.3532905691, 11908.464119114487, 11971.940140299243, 12283.692907677023], "value": 9384.708135077415, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1339.6316013096402, 1498.4980031689383, 1535.16333598189, 1645.5084690894419, 1807.7017292146797, 7822.765317040491, 8440.535770532626, 8786.560671966408, 8790.604422800974, 8962.596683149257, 8782.824343513143, 8837.513936733492, 8837.558122093886, 8844.823103537941, 8845.646174153042, 2505.0355613089, 2541.517111748771, 2545.732698066704, 2546.770790628843, 2548.095426123727, 2424.745401732819, 2543.898244070235, 2551.336652470362, 2551.59662185798, 2556.1053631229106, 2488.6267059940974, 2491.501699660066, 2531.2659328794625, 2532.3542496663326, 2533.1260715053277, 2453.116877923946, 2469.8641574713383, 2472.357187131359, 2473.591857343536, 2496.3384703053707, 2469.6665950096685, 2480.59070253408, 2494.77547020769, 2500.362407586072, 2500.9749902500926, 8587.914120858773, 8664.220220180214, 8689.348298877609, 8740.125987401272, 8788.692395766182, 5429.619926605139, 5568.638038527482, 5582.720863956786, 5589.944100558983, 5612.214289999403, 3584.605693373727, 3616.963830361689, 3666.6883314918264, 3682.558093028827, 3740.756850804716, 1151.6545036489103, 1161.5165461319384, 1186.104491109207, 1197.6407077876602, 1206.6259459567561, 12363.443645035995, 12390.876091239063, 12437.813432798515, 12449.25304481725, 12468.0, 4727.285155582496, 4785.473597904228, 4821.349117869113, 4866.6106711463, 4884.0, 9792.608295668093, 9796.412215267106, 9798.510074496247, 9808.950955245178, 9809.362391444532, 5554.805581804699, 5557.944420555824, 5560.499555039985, 5561.304836895388, 5564.972175139179, 4486.798094085774, 4658.045100754337, 4783.569478746929, 5083.923741143886, 5147.176451767699, 4010.598940105966, 4043.0903046814233, 4139.296319625671, 4150.79246037703, 4169.124483858363, 1255.209218192533, 1281.135233717237, 1287.7875150600235, 1289.2844471318508, 1294.5210272199347, 933.1881263300971, 1004.909558139771, 1016.588281745894, 1024.6772266735954, 1029.6859457865423, 6116.449519543263, 6141.877162456726, 6154.5384096192965, 6364.586301890362, 6469.5794773339585, 499.9375078115236, 507.3024591187118, 514.4546780412775, 520.945300743424, 527.1776029394157, 4118.75287482752, 4360.32414975673, 4366.432363792686, 4372.125574885035, 4382.517923028464, 10734.57061717538, 11016.614418495314, 11085.113190944705, 11104.666859994213, 11111.666650000512, 10796.838047429295, 10913.345199288067, 10990.780184396268, 11140.888591114148, 11146.888531114748, 8097.554634495042, 8145.837083258301, 8202.343812494986, 8205.425620206648, 8511.957440212884, 6035.215421995113, 6056.182415373868, 6059.787907423219, 6154.5384096192065, 6193.783217587363, 10189.694309170736, 10214.591416343561, 10217.0, 10236.48817559134, 10238.027387398186, 9288.396254243453, 9378.155965963097, 10048.296619236584, 10122.8481572775, 10351.378917264989, 6873.690683919136, 7429.034225550644, 7478.252174782696, 7612.581308028111, 7717.421193410404, 8682.262007729414, 8727.65089396417, 8841.16008979146, 8847.42491738035, 8848.646054157762, 7718.073831140299, 7812.414068944739, 7854.528728276322, 7955.164707705608, 7992.5204487730925, 7734.497257678233, 7829.804254893445, 7841.019872515935, 7973.122956474667, 8026.077001144943, 7381.889271661037, 7412.481126321321, 7492.625368731433, 7504.0, 7521.623918804147, 5274.736263186902, 5528.9447105530035, 5543.556515478671, 5559.583031272751, 5588.608797384144, 5486.780528778804, 5543.445655434424, 5639.802606908738, 5690.544756419394, 5697.344805347272], "value": 4703.547506795759, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.976341656084, 351.30617031363045, 351.3394817742645, 354.47714620934124, 360.88090929993103, 5241.240020197074, 5264.631475796691, 5275.393329767078, 5287.312649355588, 5303.681779093257, 5219.0, 5247.370315562129, 5256.789728410859, 5257.973710131444, 5272.261883336336, 3823.541175058997, 3912.5109361329833, 4018.7789671568125, 4097.590240975908, 4110.219058378913, 4132.5040995080635, 4177.603127702863, 4179.916401671965, 4180.602842729936, 4189.706720529564, 4171.770552619605, 4172.666186705056, 4173.4157217989505, 4189.706720529564, 4192.308269135589, 3996.860109896169, 4087.3255912774357, 4160.521540022906, 4174.833006679729, 4178.9373159402785, 3408.301298233856, 3610.2598967211657, 3701.6668499835146, 3723.0320116769562, 3731.1791405890663, 5082.8475145745315, 5088.516590923857, 5096.184610462308, 5111.233315002779, 5115.386153661547, 4649.1863923813535, 4734.4081989751285, 4759.833405830814, 4766.713997160146, 4772.546608072227, 5486.862828429281, 5507.0, 5510.614257002011, 5528.640638358494, 5533.557315414757, 4110.712250142491, 4115.609017143366, 4119.178362821477, 4120.443740095079, 4127.834886604533, 5449.291592093003, 5692.800751973702, 5722.456366645162, 5727.684977326246, 5729.627574207663, 4115.8765237042335, 4123.814428350732, 4126.938095928564, 4141.627253547195, 4145.0, 4137.565555616678, 4156.667466602725, 4162.354835000519, 4169.8957526062095, 4176.895577610584, 4485.170243504962, 4674.696144750648, 4725.8582242532775, 4741.122892264942, 4750.786214620351, 5545.585875601776, 5558.305211848519, 5559.305086864142, 5574.9721251393485, 5580.581456390787, 1403.515787053465, 1409.8096756937682, 1411.7458857405766, 1412.8587141285996, 1416.9433222670978, 938.6573900526396, 975.3855071305032, 981.043482604455, 985.1675334342613, 995.4574756757585, 5194.519561924833, 5202.375714914235, 5248.60635452335, 5251.947480525222, 5266.368035835724, 5519.0, 5536.446355364431, 5544.861378465571, 5551.333839939152, 5556.777728890799, 4987.301777751118, 5076.18780995039, 5148.326793792033, 5189.636725429184, 5205.505476979681, 2867.555528893025, 2895.9855200724282, 2906.8401237931694, 2914.3005678636973, 2917.4748545262055, 5715.599908006399, 5720.42795720433, 5724.742386592613, 5757.337906140764, 5757.481826635623, 5329.241350354374, 5483.75323110461, 5489.286392768914, 5500.42245564218, 5537.667739935617, 5773.8556536086935, 5791.218185544982, 5920.674362910081, 5924.851878703067, 5947.881042379128, 5675.544697412676, 5677.630953987978, 5689.431056894443, 5690.857728556819, 5707.543396528351, 5485.875395543983, 5549.445055494418, 5577.470140336662, 5599.860003499945, 5620.739970816998, 5902.970485147547, 5905.320888097924, 5923.7926672566255, 5926.288845338587, 5926.762929482945, 4034.9596504034585, 4038.818253178555, 4048.473698419187, 4049.2913740094777, 4058.492688413948, 5445.455454454523, 5616.775328986795, 5654.7455364509515, 5672.631278966854, 5685.232493613309, 5557.942701827607, 5566.471185237396, 5569.526590239713, 5573.832785016456, 5576.665400076008, 5309.681419114865, 5510.366307874646, 5550.585495309553, 5556.222128901876, 5559.19391688211, 5452.5365343946205, 5457.672539647634, 5464.726763661881, 5499.697516636624, 5502.944970550243, 5533.5849811263515, 5567.860803479945, 5568.805091821929, 5586.469285417878, 5593.0848406115065, 5302.257683924331, 5372.167314066326, 5427.05026620332, 5440.945590544202, 5445.40100588927], "value": 4222.18550594893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.0609662308607, 800.8998875140608, 850.9234168924797, 855.4140413816535, 898.7034278688034, 14605.56183314501, 15131.848681513182, 15145.6970860583, 15255.389784408624, 15412.383504659814, 14729.631759206026, 14863.256837158126, 15066.472673456432, 15077.170755608437, 15101.0, 724.7499612633659, 812.0417906869906, 828.9005319361685, 829.4857188543082, 830.211299265697, 746.9701211951516, 836.8828364029041, 862.211076864668, 868.5787393114334, 870.8780770692108, 908.6501696846695, 909.822584596004, 910.5947853205327, 910.8314961732068, 911.0252030327553, 758.4501236603473, 908.3459908865673, 909.9863502047475, 910.085364208969, 910.6448485090787, 568.7128000359833, 649.4252586461016, 663.280340830195, 664.3058004385429, 665.8401983523968, 14897.553073407815, 14921.2539373032, 14946.177960212184, 14946.32741526623, 14946.850531494654, 13969.231692256923, 14200.85799142016, 14237.78643320351, 14280.214588197645, 14385.856141438557, 12683.429245683967, 12897.856467009868, 12985.415656295394, 13076.607701768962, 13228.338583070905, 4412.955870441287, 4427.203103441379, 4483.910321793546, 4484.41702578666, 4595.563421474954, 65482.99999999953, 65950.67024664894, 65956.67021664909, 66176.0, 66216.0, 11284.379359135162, 11314.264572802742, 11372.601958931564, 11591.710207244887, 11655.650330490098, 20230.797692023185, 20274.290399835936, 20292.289769857984, 20366.0, 20402.0, 7886.211378862068, 7889.21107889218, 8023.569607090735, 8050.19498050202, 8347.666093356198, 4594.494605593382, 4688.413948256468, 4708.4820669726305, 4723.362346083303, 4861.1979023460735, 3573.874914377984, 3661.2860492204295, 3691.53855768029, 3700.7224458165747, 3703.685186759156, 642.8328634554956, 656.8128083496181, 661.2197606823897, 663.6051549328142, 664.5248647217263, 626.2672672972633, 635.8283263518824, 658.7990662847782, 673.373762400962, 708.989365159523, 9044.502552359552, 9156.0, 9184.0, 9286.72139835806, 9442.291828112919, 698.8672152291049, 713.1406654980735, 723.605634928965, 729.1359738709646, 730.1129128109267, 4385.232584297735, 4537.546245375502, 4603.631709463235, 4689.859304220879, 4694.1550520905885, 87873.56063219771, 89490.0, 91588.54205728929, 92229.0, 94846.0, 53895.0, 58022.0, 78232.0, 80721.0, 83223.0, 71161.0, 73101.0, 74341.25658743452, 74575.0, 75646.6217668908, 8943.597538110671, 9143.13140251675, 9192.402493837879, 9212.815743685223, 9342.906570934203, 41209.38185927153, 41864.3720344189, 46051.76974115108, 48181.0, 49472.50527494679, 58182.0, 58500.707496462244, 60582.69708651429, 62187.37812621816, 62260.37739622727, 12505.124641275024, 12518.436670349674, 12578.748425031632, 12733.681657958625, 12955.870441295467, 12755.234685918875, 12833.165844220095, 13116.868831311565, 13318.866811332151, 14019.859801401855, 10541.631042913768, 13290.534831280858, 13820.861791381958, 13912.234827084607, 14257.0, 12419.503219871465, 13396.397162127923, 13658.317084145554, 13678.452861885413, 13899.37452814646, 13055.738885222432, 13371.665708357368, 13480.123791953492, 13507.864921351054, 13763.862361376257, 10638.89361106379, 10981.670549883516, 11290.830637540608, 11352.318860868374, 11361.829572556253, 11005.17461190398, 11081.947215014561, 11105.666829995112, 11565.190436669352, 11786.587469438871], "value": 7326.7093224063765, "unit": "score"}], "name": "dromaeo_css", "value": 7344.223974736402, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 111.0, 111.0, 112.0, 110.0, 111.0, 111.0, 113.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 118.0, 146.0, 118.0, 121.0, 118.0, 118.0, 148.0, 119.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 160.0, 160.0, 160.0, 159.0, 159.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 108.0, 75.0, 76.0, 76.0, 75.0, 98.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 109.0, 83.0, 82.0, 82.0, 98.0, 82.0, 81.0, 110.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 53.0, 51.0, 51.0, 53.0, 50.0, 51.0, 51.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 60.0, 66.0, 60.0, 60.0, 61.0, 60.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 74.0, 75.0, 82.0, 74.0, 75.0, 81.0, 75.0, 88.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 128.0, 130.0, 124.0, 127.0, 130.0, 130.0, 119.0, 163.0, 131.0], "value": 130.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 155.0, 149.0, 155.0, 153.0, 150.0, 153.0, 159.0, 152.0, 154.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 62.0, 62.0, 64.0, 60.0, 59.0, 60.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1451.2, "alertThreshold": 2.0}]} 09:06:22 INFO - Return code: 0 09:06:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:22 INFO - # TBPL SUCCESS # 09:06:22 INFO - Running post-action listener: _resource_record_post_action 09:06:22 INFO - [mozharness: 2016-12-29 17:06:22.540906Z] Finished run-tests step (success) 09:06:22 INFO - Running post-run listener: _resource_record_post_run 09:06:22 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 354877440; Read time: 0; Write time: 668796 09:06:22 INFO - TinderboxPrint: CPU usage
13.0% 09:06:22 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:06:22 INFO - TinderboxPrint: I/O write bytes / time
354,877,440 / 668,796 09:06:22 INFO - TinderboxPrint: CPU idle
8,274.8 (87.0%) 09:06:22 INFO - TinderboxPrint: CPU user
1,188.7 (12.5%) 09:06:22 INFO - TinderboxPrint: Swap in / out
0 / 0 09:06:22 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110395392; Read time: 0; Write time: 256780 09:06:22 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 244482048; Read time: 0; Write time: 412016 09:06:23 INFO - Running post-run listener: _upload_blobber_files 09:06:23 INFO - Blob upload gear active. 09:06:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:23 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 09:06:23 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'] 09:06:23 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 09:06:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:06:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:06:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:24 INFO - (blobuploader) - INFO - Done attempting. 09:06:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:06:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:06:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:28 INFO - (blobuploader) - INFO - Done attempting. 09:06:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:06:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:29 INFO - (blobuploader) - INFO - Done attempting. 09:06:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:06:30 INFO - Return code: 0 09:06:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:06:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:06:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15954d6a9fba4b0ceb57386a0f2caa1280254338c1e4fd8fad11d3cf5fe1b3a973d9b4fad4acb17e302586c32b0adf8cd1ee8256b83f4b51df46ea9fd738e0fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d61d7f3c15d065402ae4a1d30991115770c22061f3bd61203ecf12fb8e25c4972270180882be36cb9919c75215ece2443a5d34125864c14e1ed4ae63c6fbebe8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c64dd038090c101ce94bb221cbf9f225930197a6bda80402d9038d377adcc583ef7debe3797d7149ba7863157f6d9f61f9d6811d6bd9f008d0c203ed7e51bd5"} 09:06:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:06:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:06:30 INFO - Contents: 09:06:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15954d6a9fba4b0ceb57386a0f2caa1280254338c1e4fd8fad11d3cf5fe1b3a973d9b4fad4acb17e302586c32b0adf8cd1ee8256b83f4b51df46ea9fd738e0fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d61d7f3c15d065402ae4a1d30991115770c22061f3bd61203ecf12fb8e25c4972270180882be36cb9919c75215ece2443a5d34125864c14e1ed4ae63c6fbebe8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c64dd038090c101ce94bb221cbf9f225930197a6bda80402d9038d377adcc583ef7debe3797d7149ba7863157f6d9f61f9d6811d6bd9f008d0c203ed7e51bd5"} 09:06:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:06:30 INFO - Copying logs to upload dir... 09:06:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:06:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.291267 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-29 09:06:29.784909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 09:06:29.789061) ========= 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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 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/15954d6a9fba4b0ceb57386a0f2caa1280254338c1e4fd8fad11d3cf5fe1b3a973d9b4fad4acb17e302586c32b0adf8cd1ee8256b83f4b51df46ea9fd738e0fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d61d7f3c15d065402ae4a1d30991115770c22061f3bd61203ecf12fb8e25c4972270180882be36cb9919c75215ece2443a5d34125864c14e1ed4ae63c6fbebe8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c64dd038090c101ce94bb221cbf9f225930197a6bda80402d9038d377adcc583ef7debe3797d7149ba7863157f6d9f61f9d6811d6bd9f008d0c203ed7e51bd5"} build_url:https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011801 build_url: 'https://queue.taskcluster.net/v1/task/e7ujqma-RPOKeT1p4Uh43g/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15954d6a9fba4b0ceb57386a0f2caa1280254338c1e4fd8fad11d3cf5fe1b3a973d9b4fad4acb17e302586c32b0adf8cd1ee8256b83f4b51df46ea9fd738e0fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d61d7f3c15d065402ae4a1d30991115770c22061f3bd61203ecf12fb8e25c4972270180882be36cb9919c75215ece2443a5d34125864c14e1ed4ae63c6fbebe8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c64dd038090c101ce94bb221cbf9f225930197a6bda80402d9038d377adcc583ef7debe3797d7149ba7863157f6d9f61f9d6811d6bd9f008d0c203ed7e51bd5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 09:06:29.815056) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:06:29.815416) ========= 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=8783db0840cc980e80bb0bd400000236-1483029949.776307-1295155399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 09:06:29.902789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 09:06:29.903153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 09:06:29.903512) ========= ========= Total master_lag: 0.18 =========