builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1480479395.5 results: success (0) buildid: 20161129171714 builduid: 4c697f9685534775b1330889cfcae254 revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.498369) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.499458) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.499894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.516649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.517363) ========= 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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-29 20:16:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-11-29 20:16:35 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.078005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.615100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.615988) ========= 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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016734 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.667337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:35.667766) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-29 20:16:35,991 truncating revision to first 12 chars 2016-11-29 20:16:35,991 Setting DEBUG logging. 2016-11-29 20:16:35,991 attempt 1/10 2016-11-29 20:16:35,991 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/adcc39e3cad0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 20:16:36,354 unpacking tar archive at: mozilla-central-adcc39e3cad0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686779 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:36.371839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:36.372633) ========= script_repo_revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:36.373379) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:36.373728) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:36.385365) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-11-29 20:16:36.386070) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:16:36 INFO - MultiFileLogger online at 20161129 20:16:36 in /builds/slave/test 20:16:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:16:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:16:36 INFO - {'append_to_log': False, 20:16:36 INFO - 'base_work_dir': '/builds/slave/test', 20:16:36 INFO - 'blob_upload_branch': 'Firefox', 20:16:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:16:36 INFO - 'branch': 'Firefox', 20:16:36 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:36 INFO - 'config_files': ('talos/linux_config.py',), 20:16:36 INFO - 'default_actions': ('clobber', 20:16:36 INFO - 'read-buildbot-config', 20:16:36 INFO - 'download-and-extract', 20:16:36 INFO - 'populate-webroot', 20:16:36 INFO - 'create-virtualenv', 20:16:36 INFO - 'install', 20:16:36 INFO - 'run-tests'), 20:16:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:36 INFO - 'download_minidump_stackwalk': True, 20:16:36 INFO - 'download_symbols': 'ondemand', 20:16:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:16:36 INFO - 'tooltool.py': '/tools/tooltool.py', 20:16:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:16:36 INFO - '/tools/misc-python/virtualenv.py')}, 20:16:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:36 INFO - 'installer_path': 'installer.exe', 20:16:36 INFO - 'log_level': 'info', 20:16:36 INFO - 'log_name': 'talos', 20:16:36 INFO - 'log_to_console': True, 20:16:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:16:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:16:36 INFO - 'opt_config_files': (), 20:16:36 INFO - 'pip_index': False, 20:16:36 INFO - 'sps_profile': False, 20:16:36 INFO - 'sps_profile_interval': 0, 20:16:36 INFO - 'suite': 'dromaeojs', 20:16:36 INFO - 'system_bits': '32', 20:16:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:36 INFO - 'title': 'talos-linux64-ix-045', 20:16:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:16:36 INFO - 'use_talos_json': True, 20:16:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:16:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:36 INFO - 'work_dir': 'build'} 20:16:36 INFO - [mozharness: 2016-11-30 04:16:36.807692Z] Running clobber step. 20:16:36 INFO - Running pre-action listener: _resource_record_pre_action 20:16:36 INFO - Running main action method: clobber 20:16:36 INFO - rmtree: /builds/slave/test/build 20:16:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:16:37 INFO - Running post-action listener: _resource_record_post_action 20:16:37 INFO - [mozharness: 2016-11-30 04:16:37.371507Z] Finished clobber step (success) 20:16:37 INFO - [mozharness: 2016-11-30 04:16:37.371607Z] Running read-buildbot-config step. 20:16:37 INFO - Running pre-action listener: _resource_record_pre_action 20:16:37 INFO - Running main action method: read_buildbot_config 20:16:37 INFO - Using buildbot properties: 20:16:37 INFO - { 20:16:37 INFO - "project": "", 20:16:37 INFO - "product": "firefox", 20:16:37 INFO - "script_repo_revision": "production", 20:16:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 20:16:37 INFO - "repository": "", 20:16:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 20:16:37 INFO - "buildid": "20161129171714", 20:16:37 INFO - "stage_platform": "linux64-pgo", 20:16:37 INFO - "basedir": "/builds/slave/test", 20:16:37 INFO - "nightly_build": "True", 20:16:37 INFO - "buildnumber": 112, 20:16:37 INFO - "slavename": "talos-linux64-ix-045", 20:16:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:16:37 INFO - "platform": "ubuntu64_hw", 20:16:37 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 20:16:37 INFO - "branch": "mozilla-central", 20:16:37 INFO - "repo_path": "mozilla-central", 20:16:37 INFO - "revision": "adcc39e3cad0f32aba0efb478cc4a023a5dfc43f", 20:16:37 INFO - "pgo_build": "True", 20:16:37 INFO - "builduid": "4c697f9685534775b1330889cfcae254", 20:16:37 INFO - "slavebuilddir": "test" 20:16:37 INFO - } 20:16:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 20:16:37 INFO - Running post-action listener: _resource_record_post_action 20:16:37 INFO - [mozharness: 2016-11-30 04:16:37.374171Z] Finished read-buildbot-config step (success) 20:16:37 INFO - [mozharness: 2016-11-30 04:16:37.374261Z] Running download-and-extract step. 20:16:37 INFO - Running pre-action listener: _resource_record_pre_action 20:16:37 INFO - Running main action method: download_and_extract 20:16:37 INFO - mkdir: /builds/slave/test/build/tests 20:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:37 INFO - trying https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:16:37 INFO - Downloading https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:16:37 INFO - Downloaded 1361 bytes. 20:16:37 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 20:16:37 INFO - Using the following test package requirements: 20:16:37 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:37 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:16:37 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:16:37 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'jsshell-linux-x86_64.zip'], 20:16:37 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:16:37 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 20:16:37 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:16:37 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:16:37 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:16:37 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 20:16:37 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:16:37 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:16:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 20:16:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:16:37 INFO - Fetch https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:16:38 INFO - Http code: 200 20:16:38 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 20:16:38 INFO - x-amz-cf-id: oGRE5p8i0DtP9qJ_D6gs1cS_RwpNQzRyJc7Hmsx9FFCR0Iq82GGFnQ== 20:16:38 INFO - x-amz-version-id: yYBXyLuuo3BjSs2doSmoRWMqsAW4I6bQ 20:16:38 INFO - Expected file size: 21782535 20:16:38 INFO - Obtained file size: 21782535 20:16:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:16:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 20:16:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:16:42 INFO - Fetch https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:16:43 INFO - Http code: 200 20:16:43 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 20:16:43 INFO - x-amz-cf-id: 5gtYT0QedImEBvIi0mh7wHuHOAOoXimmlSViSdBZLhd32TtPK9Yd1g== 20:16:43 INFO - x-amz-version-id: yYBXyLuuo3BjSs2doSmoRWMqsAW4I6bQ 20:16:43 INFO - Expected file size: 21782535 20:16:43 INFO - Obtained file size: 21782535 20:16:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 20:16:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:16:48 INFO - Fetch https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 20:16:48 INFO - Http code: 200 20:16:48 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 20:16:48 INFO - x-amz-cf-id: rYRx5YjZ5U4_4pCGXdojj8XURb2SQy2TqZ0X7tduLJNUdsrNPuQ2Mw== 20:16:48 INFO - x-amz-version-id: Zn8Jm63NtrqaZPVGQhILZ1OGHfzRpit4 20:16:48 INFO - Expected file size: 14409702 20:16:48 INFO - Obtained file size: 14409702 20:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:48 INFO - trying https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:16:48 INFO - Downloading https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:16:50 INFO - Downloaded 59785544 bytes. 20:16:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:16:50 INFO - mkdir: /builds/slave/test/properties 20:16:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:50 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:50 INFO - Contents: 20:16:50 INFO - build_url:https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 20:16:50 INFO - Running post-action listener: _resource_record_post_action 20:16:50 INFO - Running post-action listener: set_extra_try_arguments 20:16:50 INFO - [mozharness: 2016-11-30 04:16:50.743351Z] Finished download-and-extract step (success) 20:16:50 INFO - [mozharness: 2016-11-30 04:16:50.743575Z] Running populate-webroot step. 20:16:50 INFO - Running pre-action listener: _resource_record_pre_action 20:16:50 INFO - Running main action method: populate_webroot 20:16:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:16:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:16:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:16:50 INFO - '--tppagecycles', 20:16:50 INFO - '7'], 20:16:50 INFO - 'tests': ['tsvgm']}}, 20:16:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:16:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:16:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:16:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:16:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['damp', 'tps']}, 20:16:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'tests': ['damp', 'tps']}, 20:16:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['dromaeo_dom']}, 20:16:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:16:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['basic_compositor_video']}, 20:16:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:16:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'other-e10s': {'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['a11yr', 20:16:50 INFO - 'ts_paint', 20:16:50 INFO - 'tpaint', 20:16:50 INFO - 'sessionrestore', 20:16:50 INFO - 'sessionrestore_no_auto_restore', 20:16:50 INFO - 'tabpaint']}, 20:16:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['tsvgx', 20:16:50 INFO - 'tsvgr_opacity', 20:16:50 INFO - 'tart', 20:16:50 INFO - 'tscrollx', 20:16:50 INFO - 'cart', 20:16:50 INFO - 'tsvg_static']}, 20:16:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:16:50 INFO - 'tsvgr_opacity', 20:16:50 INFO - 'tart', 20:16:50 INFO - 'tscrollx', 20:16:50 INFO - 'cart', 20:16:50 INFO - 'tsvg_static']}, 20:16:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'talos_options': ['--disable-e10s'], 20:16:50 INFO - 'tests': ['tp5o']}, 20:16:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:16:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'talos_options': ['--disable-e10s', 20:16:50 INFO - '--xperf_path', 20:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:50 INFO - 'tests': ['tp5n']}, 20:16:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:16:50 INFO - 'talos_options': ['--xperf_path', 20:16:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:50 INFO - 'tests': ['tp5n']}}, 20:16:50 INFO - 'talos.zip': {'path': '', 20:16:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:16:50 INFO - Running post-action listener: _resource_record_post_action 20:16:50 INFO - [mozharness: 2016-11-30 04:16:50.765020Z] Finished populate-webroot step (success) 20:16:50 INFO - [mozharness: 2016-11-30 04:16:50.765118Z] Running create-virtualenv step. 20:16:50 INFO - Running pre-action listener: _resource_record_pre_action 20:16:50 INFO - Running main action method: create_virtualenv 20:16:50 INFO - Creating virtualenv /builds/slave/test/build/venv 20:16:50 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 20:16:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:16:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:16:50 INFO - Using real prefix '/usr' 20:16:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:16:51 INFO - Installing distribute.............................................................................................................................................................................................done. 20:16:53 INFO - Installing pip.................done. 20:16:53 INFO - Return code: 0 20:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:16:53 INFO - Reading from file tmpfile_stdout 20:16:53 INFO - Output received: 20:16:53 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) 20:16:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:16:53 INFO - Using env: {'DISPLAY': ':0', 20:16:53 INFO - 'HOME': '/home/cltbld', 20:16:53 INFO - 'LANG': 'en_US.UTF-8', 20:16:53 INFO - 'LANGUAGE': 'en_US:en', 20:16:53 INFO - 'LOGNAME': 'cltbld', 20:16:53 INFO - 'MAIL': '/var/mail/cltbld', 20:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:53 INFO - 'MOZ_NO_REMOTE': '1', 20:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:53 INFO - 'NO_EM_RESTART': '1', 20:16:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:53 INFO - 'PWD': '/builds/slave/test', 20:16:53 INFO - 'SHELL': '/bin/bash', 20:16:53 INFO - 'SHLVL': '1', 20:16:53 INFO - 'TERM': 'linux', 20:16:53 INFO - 'TMOUT': '86400', 20:16:53 INFO - 'USER': 'cltbld', 20:16:53 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:53 INFO - '_': '/tools/buildbot/bin/python'} 20:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:53 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 20:16:53 INFO - Cleaning up... 20:16:53 INFO - Return code: 0 20:16:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:16:53 INFO - Using env: {'DISPLAY': ':0', 20:16:53 INFO - 'HOME': '/home/cltbld', 20:16:53 INFO - 'LANG': 'en_US.UTF-8', 20:16:53 INFO - 'LANGUAGE': 'en_US:en', 20:16:53 INFO - 'LOGNAME': 'cltbld', 20:16:53 INFO - 'MAIL': '/var/mail/cltbld', 20:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:53 INFO - 'MOZ_NO_REMOTE': '1', 20:16:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:53 INFO - 'NO_EM_RESTART': '1', 20:16:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:53 INFO - 'PWD': '/builds/slave/test', 20:16:53 INFO - 'SHELL': '/bin/bash', 20:16:53 INFO - 'SHLVL': '1', 20:16:53 INFO - 'TERM': 'linux', 20:16:53 INFO - 'TMOUT': '86400', 20:16:53 INFO - 'USER': 'cltbld', 20:16:53 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:53 INFO - '_': '/tools/buildbot/bin/python'} 20:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:54 INFO - Downloading/unpacking psutil>=3.1.1 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:54 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 20:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:56 INFO - Installing collected packages: psutil 20:16:56 INFO - Running setup.py install for psutil 20:16:56 INFO - building 'psutil._psutil_linux' extension 20:16:56 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 20:16:56 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 20:16:56 INFO - building 'psutil._psutil_posix' extension 20:16:56 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 20:16:56 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 20:16:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:56 INFO - Successfully installed psutil 20:16:56 INFO - Cleaning up... 20:16:56 INFO - Return code: 0 20:16:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:16:56 INFO - Using env: {'DISPLAY': ':0', 20:16:56 INFO - 'HOME': '/home/cltbld', 20:16:56 INFO - 'LANG': 'en_US.UTF-8', 20:16:56 INFO - 'LANGUAGE': 'en_US:en', 20:16:56 INFO - 'LOGNAME': 'cltbld', 20:16:56 INFO - 'MAIL': '/var/mail/cltbld', 20:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:56 INFO - 'MOZ_NO_REMOTE': '1', 20:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:56 INFO - 'NO_EM_RESTART': '1', 20:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:56 INFO - 'PWD': '/builds/slave/test', 20:16:56 INFO - 'SHELL': '/bin/bash', 20:16:56 INFO - 'SHLVL': '1', 20:16:56 INFO - 'TERM': 'linux', 20:16:56 INFO - 'TMOUT': '86400', 20:16:56 INFO - 'USER': 'cltbld', 20:16:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:56 INFO - '_': '/tools/buildbot/bin/python'} 20:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:16:56 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 20:16:56 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 20:16:56 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 20:16:56 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 20:16:56 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 20:16:56 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 20:16:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:16:58 INFO - Installing collected packages: mozsystemmonitor 20:16:58 INFO - Running setup.py install for mozsystemmonitor 20:16:59 INFO - Successfully installed mozsystemmonitor 20:16:59 INFO - Cleaning up... 20:16:59 INFO - Return code: 0 20:16:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:16:59 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')]} 20:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:59 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')]} 20:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:59 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:16:59 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 20:16:59 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 20:16:59 INFO - Using env: {'DISPLAY': ':0', 20:16:59 INFO - 'HOME': '/home/cltbld', 20:16:59 INFO - 'LANG': 'en_US.UTF-8', 20:16:59 INFO - 'LANGUAGE': 'en_US:en', 20:16:59 INFO - 'LOGNAME': 'cltbld', 20:16:59 INFO - 'MAIL': '/var/mail/cltbld', 20:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:59 INFO - 'MOZ_NO_REMOTE': '1', 20:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:16:59 INFO - 'NO_EM_RESTART': '1', 20:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:59 INFO - 'PWD': '/builds/slave/test', 20:16:59 INFO - 'SHELL': '/bin/bash', 20:16:59 INFO - 'SHLVL': '1', 20:16:59 INFO - 'TERM': 'linux', 20:16:59 INFO - 'TMOUT': '86400', 20:16:59 INFO - 'USER': 'cltbld', 20:16:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:59 INFO - '_': '/tools/buildbot/bin/python'} 20:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:59 INFO - Downloading/unpacking jsonschema==2.5.1 20:16:59 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 20:16:59 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 20:16:59 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 20:16:59 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 20:16:59 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 20:16:59 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 20:17:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:17:01 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 20:17:01 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 20:17:01 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 20:17:01 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 20:17:01 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 20:17:01 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 20:17:01 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:01 INFO - warning: no files found matching '*.txt' 20:17:01 INFO - no previously-included directories found matching 'build' 20:17:01 INFO - no previously-included directories found matching 'dist' 20:17:01 INFO - no previously-included directories found matching '.git*' 20:17:01 INFO - Installing collected packages: jsonschema, functools32 20:17:01 INFO - Running setup.py install for functools32 20:17:01 INFO - warning: no files found matching '*.txt' 20:17:01 INFO - no previously-included directories found matching 'build' 20:17:01 INFO - no previously-included directories found matching 'dist' 20:17:01 INFO - no previously-included directories found matching '.git*' 20:17:01 INFO - Successfully installed jsonschema functools32 20:17:01 INFO - Cleaning up... 20:17:02 INFO - Return code: 0 20:17:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:02 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:02 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 20:17:02 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 20:17:02 INFO - Using env: {'DISPLAY': ':0', 20:17:02 INFO - 'HOME': '/home/cltbld', 20:17:02 INFO - 'LANG': 'en_US.UTF-8', 20:17:02 INFO - 'LANGUAGE': 'en_US:en', 20:17:02 INFO - 'LOGNAME': 'cltbld', 20:17:02 INFO - 'MAIL': '/var/mail/cltbld', 20:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:02 INFO - 'MOZ_NO_REMOTE': '1', 20:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:02 INFO - 'NO_EM_RESTART': '1', 20:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:02 INFO - 'PWD': '/builds/slave/test', 20:17:02 INFO - 'SHELL': '/bin/bash', 20:17:02 INFO - 'SHLVL': '1', 20:17:02 INFO - 'TERM': 'linux', 20:17:02 INFO - 'TMOUT': '86400', 20:17:02 INFO - 'USER': 'cltbld', 20:17:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:02 INFO - '_': '/tools/buildbot/bin/python'} 20:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:17:02 INFO - Cleaning up... 20:17:02 INFO - Return code: 0 20:17:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:02 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:02 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 20:17:02 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 20:17:02 INFO - Using env: {'DISPLAY': ':0', 20:17:02 INFO - 'HOME': '/home/cltbld', 20:17:02 INFO - 'LANG': 'en_US.UTF-8', 20:17:02 INFO - 'LANGUAGE': 'en_US:en', 20:17:02 INFO - 'LOGNAME': 'cltbld', 20:17:02 INFO - 'MAIL': '/var/mail/cltbld', 20:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:02 INFO - 'MOZ_NO_REMOTE': '1', 20:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:02 INFO - 'NO_EM_RESTART': '1', 20:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:02 INFO - 'PWD': '/builds/slave/test', 20:17:02 INFO - 'SHELL': '/bin/bash', 20:17:02 INFO - 'SHLVL': '1', 20:17:02 INFO - 'TERM': 'linux', 20:17:02 INFO - 'TMOUT': '86400', 20:17:02 INFO - 'USER': 'cltbld', 20:17:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:02 INFO - '_': '/tools/buildbot/bin/python'} 20:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:02 INFO - Downloading/unpacking blobuploader==1.2.4 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:04 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:04 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 20:17:05 INFO - Downloading docopt-0.6.1.tar.gz 20:17:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:05 INFO - Running setup.py install for blobuploader 20:17:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:05 INFO - Running setup.py install for requests 20:17:05 INFO - Running setup.py install for docopt 20:17:05 INFO - Successfully installed blobuploader requests docopt 20:17:05 INFO - Cleaning up... 20:17:05 INFO - Return code: 0 20:17:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:05 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')]} 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 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')]} 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:05 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 20:17:05 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 20:17:05 INFO - Using env: {'DISPLAY': ':0', 20:17:05 INFO - 'HOME': '/home/cltbld', 20:17:05 INFO - 'LANG': 'en_US.UTF-8', 20:17:05 INFO - 'LANGUAGE': 'en_US:en', 20:17:05 INFO - 'LOGNAME': 'cltbld', 20:17:05 INFO - 'MAIL': '/var/mail/cltbld', 20:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:05 INFO - 'MOZ_NO_REMOTE': '1', 20:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:05 INFO - 'NO_EM_RESTART': '1', 20:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:05 INFO - 'PWD': '/builds/slave/test', 20:17:05 INFO - 'SHELL': '/bin/bash', 20:17:05 INFO - 'SHLVL': '1', 20:17:05 INFO - 'TERM': 'linux', 20:17:05 INFO - 'TMOUT': '86400', 20:17:05 INFO - 'USER': 'cltbld', 20:17:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:05 INFO - '_': '/tools/buildbot/bin/python'} 20:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:06 INFO - Running setup.py (path:/tmp/pip-5ECA2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:06 INFO - Running setup.py (path:/tmp/pip-UFuNvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:06 INFO - Running setup.py (path:/tmp/pip-DWGeOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:06 INFO - Running setup.py (path:/tmp/pip-gMuhJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:06 INFO - Running setup.py (path:/tmp/pip-Z7wwD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:06 INFO - Running setup.py (path:/tmp/pip-EyncZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:06 INFO - Running setup.py (path:/tmp/pip-tmx5ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:06 INFO - Running setup.py (path:/tmp/pip-_89xCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:06 INFO - Running setup.py (path:/tmp/pip-C5rrkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:06 INFO - Running setup.py (path:/tmp/pip-zSfAjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:06 INFO - Running setup.py (path:/tmp/pip-zylbu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:06 INFO - Running setup.py (path:/tmp/pip-WchvSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:06 INFO - Running setup.py (path:/tmp/pip-Izy07c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:07 INFO - Running setup.py (path:/tmp/pip-F15Bh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:07 INFO - Running setup.py (path:/tmp/pip-pz2vgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:07 INFO - Running setup.py (path:/tmp/pip-z57st3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:07 INFO - Running setup.py (path:/tmp/pip-AtNTQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:07 INFO - Running setup.py install for manifestparser 20:17:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Running setup.py install for mozcrash 20:17:07 INFO - Running setup.py install for mozdebug 20:17:07 INFO - Running setup.py install for mozdevice 20:17:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Running setup.py install for mozfile 20:17:07 INFO - Running setup.py install for mozhttpd 20:17:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:07 INFO - Running setup.py install for mozinfo 20:17:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for mozInstall 20:17:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for mozleak 20:17:08 INFO - Running setup.py install for mozlog 20:17:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for moznetwork 20:17:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for mozprocess 20:17:08 INFO - Running setup.py install for mozprofile 20:17:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for mozrunner 20:17:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:08 INFO - Running setup.py install for mozscreenshot 20:17:08 INFO - Running setup.py install for moztest 20:17:08 INFO - Running setup.py install for mozversion 20:17:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:09 INFO - Cleaning up... 20:17:09 INFO - Return code: 0 20:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:09 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:09 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 20:17:09 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 20:17:09 INFO - Using env: {'DISPLAY': ':0', 20:17:09 INFO - 'HOME': '/home/cltbld', 20:17:09 INFO - 'LANG': 'en_US.UTF-8', 20:17:09 INFO - 'LANGUAGE': 'en_US:en', 20:17:09 INFO - 'LOGNAME': 'cltbld', 20:17:09 INFO - 'MAIL': '/var/mail/cltbld', 20:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:09 INFO - 'MOZ_NO_REMOTE': '1', 20:17:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:09 INFO - 'NO_EM_RESTART': '1', 20:17:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:09 INFO - 'PWD': '/builds/slave/test', 20:17:09 INFO - 'SHELL': '/bin/bash', 20:17:09 INFO - 'SHLVL': '1', 20:17:09 INFO - 'TERM': 'linux', 20:17:09 INFO - 'TMOUT': '86400', 20:17:09 INFO - 'USER': 'cltbld', 20:17:09 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:09 INFO - '_': '/tools/buildbot/bin/python'} 20:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:09 INFO - Running setup.py (path:/tmp/pip-mWwW3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:09 INFO - Running setup.py (path:/tmp/pip-og7HCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:09 INFO - Running setup.py (path:/tmp/pip-HLiU8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:09 INFO - Running setup.py (path:/tmp/pip-iLoT1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:09 INFO - Running setup.py (path:/tmp/pip-zZ57G_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:09 INFO - Running setup.py (path:/tmp/pip-xTwrqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:09 INFO - Running setup.py (path:/tmp/pip-wXNkNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:09 INFO - Running setup.py (path:/tmp/pip-rW_mPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:09 INFO - Running setup.py (path:/tmp/pip-l2TR9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:09 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)) 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:09 INFO - Running setup.py (path:/tmp/pip-0yNxY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:10 INFO - Running setup.py (path:/tmp/pip-4kkv1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:10 INFO - Running setup.py (path:/tmp/pip-BTr3T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:10 INFO - Running setup.py (path:/tmp/pip-Bh6iZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:10 INFO - Running setup.py (path:/tmp/pip-qjdT2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:10 INFO - Running setup.py (path:/tmp/pip-YKlegn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:10 INFO - Running setup.py (path:/tmp/pip-ZkBg28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:10 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)) 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:10 INFO - Running setup.py (path:/tmp/pip-W0quRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:10 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)) 20:17:10 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)) 20:17:10 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)) 20:17:10 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)) 20:17:10 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:17:10 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:12 INFO - Downloading blessings-1.6.tar.gz 20:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:12 INFO - Installing collected packages: blessings 20:17:12 INFO - Running setup.py install for blessings 20:17:12 INFO - Successfully installed blessings 20:17:12 INFO - Cleaning up... 20:17:12 INFO - Return code: 0 20:17:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:13 INFO - Reading from file tmpfile_stdout 20:17:13 INFO - Current package versions: 20:17:13 INFO - argparse == 1.2.1 20:17:13 INFO - blessings == 1.6 20:17:13 INFO - blobuploader == 1.2.4 20:17:13 INFO - docopt == 0.6.1 20:17:13 INFO - functools32 == 3.2.3-2 20:17:13 INFO - jsonschema == 2.5.1 20:17:13 INFO - manifestparser == 1.1 20:17:13 INFO - mozInstall == 1.12 20:17:13 INFO - mozcrash == 1.0 20:17:13 INFO - mozdebug == 0.1 20:17:13 INFO - mozdevice == 0.49 20:17:13 INFO - mozfile == 1.2 20:17:13 INFO - mozhttpd == 0.7 20:17:13 INFO - mozinfo == 0.9 20:17:13 INFO - mozleak == 0.1 20:17:13 INFO - mozlog == 3.4 20:17:13 INFO - moznetwork == 0.27 20:17:13 INFO - mozprocess == 0.23 20:17:13 INFO - mozprofile == 0.28 20:17:13 INFO - mozrunner == 6.13 20:17:13 INFO - mozscreenshot == 0.1 20:17:13 INFO - mozsystemmonitor == 0.3 20:17:13 INFO - moztest == 0.7 20:17:13 INFO - mozversion == 1.4 20:17:13 INFO - psutil == 3.1.1 20:17:13 INFO - requests == 1.2.3 20:17:13 INFO - wsgiref == 0.1.2 20:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:13 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:13 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 20:17:13 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 20:17:13 INFO - Using env: {'DISPLAY': ':0', 20:17:13 INFO - 'HOME': '/home/cltbld', 20:17:13 INFO - 'LANG': 'en_US.UTF-8', 20:17:13 INFO - 'LANGUAGE': 'en_US:en', 20:17:13 INFO - 'LOGNAME': 'cltbld', 20:17:13 INFO - 'MAIL': '/var/mail/cltbld', 20:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:13 INFO - 'MOZ_NO_REMOTE': '1', 20:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:13 INFO - 'NO_EM_RESTART': '1', 20:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:13 INFO - 'PWD': '/builds/slave/test', 20:17:13 INFO - 'SHELL': '/bin/bash', 20:17:13 INFO - 'SHLVL': '1', 20:17:13 INFO - 'TERM': 'linux', 20:17:13 INFO - 'TMOUT': '86400', 20:17:13 INFO - 'USER': 'cltbld', 20:17:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:13 INFO - '_': '/tools/buildbot/bin/python'} 20:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 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)) 20:17:13 INFO - Cleaning up... 20:17:13 INFO - Return code: 0 20:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:13 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 0x7f80669c2e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23b8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2554130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, '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': '4300f881736b422476174874000001bc-1480479394.856700-101636399', '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 20:17:13 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 20:17:13 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 20:17:13 INFO - Using env: {'DISPLAY': ':0', 20:17:13 INFO - 'HOME': '/home/cltbld', 20:17:13 INFO - 'LANG': 'en_US.UTF-8', 20:17:13 INFO - 'LANGUAGE': 'en_US:en', 20:17:13 INFO - 'LOGNAME': 'cltbld', 20:17:13 INFO - 'MAIL': '/var/mail/cltbld', 20:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:13 INFO - 'MOZ_NO_REMOTE': '1', 20:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:13 INFO - 'NO_EM_RESTART': '1', 20:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:13 INFO - 'PWD': '/builds/slave/test', 20:17:13 INFO - 'SHELL': '/bin/bash', 20:17:13 INFO - 'SHLVL': '1', 20:17:13 INFO - 'TERM': 'linux', 20:17:13 INFO - 'TMOUT': '86400', 20:17:13 INFO - 'USER': 'cltbld', 20:17:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:13 INFO - '_': '/tools/buildbot/bin/python'} 20:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:17:13 INFO - Cleaning up... 20:17:13 INFO - Return code: 0 20:17:13 INFO - Running post-action listener: _resource_record_post_action 20:17:13 INFO - Running post-action listener: _start_resource_monitoring 20:17:13 INFO - Starting resource monitoring. 20:17:13 INFO - [mozharness: 2016-11-30 04:17:13.522961Z] Finished create-virtualenv step (success) 20:17:13 INFO - [mozharness: 2016-11-30 04:17:13.523487Z] Running install step. 20:17:13 INFO - Running pre-action listener: _resource_record_pre_action 20:17:13 INFO - Running main action method: install 20:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:13 INFO - Reading from file tmpfile_stdout 20:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:13 INFO - Reading from file tmpfile_stdout 20:17:13 INFO - Output received: 20:17:13 INFO - Usage: mozinstall [options] installer 20:17:13 INFO - Options: 20:17:13 INFO - -h, --help show this help message and exit 20:17:13 INFO - -d DEST, --destination=DEST 20:17:13 INFO - Directory to install application into. [default: 20:17:13 INFO - "/builds/slave/test"] 20:17:13 INFO - --app=APP Application being installed. [default: firefox] 20:17:13 INFO - mkdir: /builds/slave/test/build/application 20:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:17:30 INFO - Reading from file tmpfile_stdout 20:17:30 INFO - Output received: 20:17:30 INFO - /builds/slave/test/build/application/firefox/firefox 20:17:30 INFO - Running post-action listener: _resource_record_post_action 20:17:30 INFO - [mozharness: 2016-11-30 04:17:30.778914Z] Finished install step (success) 20:17:30 INFO - [mozharness: 2016-11-30 04:17:30.779153Z] Running run-tests step. 20:17:30 INFO - Running pre-action listener: _resource_record_pre_action 20:17:30 INFO - Running main action method: run_tests 20:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:17:30 INFO - Python 2.7.3 20:17:30 INFO - Return code: 0 20:17:30 INFO - grabbing minidump binary from tooltool 20:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:30 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 0x25e2fc0>, '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 0x25e3ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25e4350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:17:30 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 20:17:30 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 20:17:30 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 20:17:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:17:30 INFO - Return code: 0 20:17:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:17:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:17:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:17:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:17:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 20:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 20:17:30 INFO - Using env: {'DISPLAY': ':0', 20:17:30 INFO - 'HOME': '/home/cltbld', 20:17:30 INFO - 'LANG': 'en_US.UTF-8', 20:17:30 INFO - 'LANGUAGE': 'en_US:en', 20:17:30 INFO - 'LOGNAME': 'cltbld', 20:17:30 INFO - 'MAIL': '/var/mail/cltbld', 20:17:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:17:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:30 INFO - 'MOZ_NO_REMOTE': '1', 20:17:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:17:30 INFO - 'NO_EM_RESTART': '1', 20:17:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:30 INFO - 'PWD': '/builds/slave/test', 20:17:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:17:30 INFO - 'SHELL': '/bin/bash', 20:17:30 INFO - 'SHLVL': '1', 20:17:30 INFO - 'TERM': 'linux', 20:17:30 INFO - 'TMOUT': '86400', 20:17:30 INFO - 'USER': 'cltbld', 20:17:30 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1480479394.856700-101636399', 20:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:30 INFO - '_': '/tools/buildbot/bin/python'} 20:17:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 20:17:31 INFO - mozversion application_buildid: 20161129171714 20:17:31 INFO - mozversion application_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 20:17:31 INFO - mozversion application_display_name: Nightly 20:17:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:17:31 INFO - mozversion application_name: Firefox 20:17:31 INFO - mozversion application_remotingname: firefox 20:17:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:17:31 INFO - mozversion application_vendor: Mozilla 20:17:31 INFO - mozversion application_version: 53.0a1 20:17:31 INFO - mozversion platform_buildid: 20161129171714 20:17:31 INFO - mozversion platform_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 20:17:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:17:31 INFO - mozversion platform_version: 53.0a1 20:17:31 INFO - using testdate: 1480479451 20:17:31 INFO - actual date: 1480479451 20:17:31 INFO - starting webserver on 'localhost:45634' 20:17:31 INFO - SUITE-START | Running 2 tests 20:17:31 INFO - TEST-START | dromaeo_css 20:17:31 INFO - operating with platform_type : linux_ 20:17:31 INFO - Initialising browser for dromaeo_css test... 20:17:31 INFO - TEST-INFO | started process 2054 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDs7uML/profile http://localhost:45634/getInfo.html) 20:17:35 INFO - PROCESS | 2054 | __metrics Screen width/height:1600/1200 20:17:35 INFO - PROCESS | 2054 | colorDepth:24 20:17:35 INFO - PROCESS | 2054 | Browser inner width/height: 1024/697 20:17:35 INFO - PROCESS | 2054 | __metrics 20:17:36 INFO - PROCESS | 2054 | 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" 20:17:36 INFO - PROCESS | 2054 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:17:36 INFO - PROCESS | 2054 | console.error: PushService: 20:17:36 INFO - PROCESS | 2054 | stateChangeProcessEnqueue: Error transitioning state 20:17:36 INFO - PROCESS | 2054 | UnknownError 20:17:37 INFO - TEST-INFO | 2054: exit 0 20:17:37 INFO - Browser initialized. 20:17:37 INFO - Running cycle 1/1 for dromaeo_css test... 20:17:37 INFO - TEST-INFO | started process 2137 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDs7uML/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:17:49 INFO - PROCESS | 2137 | RSS: Main: 174084096 20:17:49 INFO - PROCESS | 2137 | 20:18:41 INFO - PROCESS | 2137 | 1480479521216 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:20:51 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45634/tests/dromaeo/cssquery-ext.html) 20:20:53 INFO - PROCESS | 2137 | RSS: Main: 273825792 20:20:53 INFO - PROCESS | 2137 | 20:23:55 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-ext.html (next: http://localhost:45634/tests/dromaeo/cssquery-jquery.html) 20:23:56 INFO - PROCESS | 2137 | RSS: Main: 283107328 20:23:56 INFO - PROCESS | 2137 | 20:26:58 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45634/tests/dromaeo/cssquery-mootools.html) 20:26:59 INFO - PROCESS | 2137 | RSS: Main: 216555520 20:26:59 INFO - PROCESS | 2137 | 20:30:01 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45634/tests/dromaeo/cssquery-prototype.html) 20:30:01 INFO - PROCESS | 2137 | RSS: Main: 251531264 20:30:01 INFO - PROCESS | 2137 | 20:33:04 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45634/tests/dromaeo/cssquery-yui.html) 20:33:05 INFO - PROCESS | 2137 | RSS: Main: 216559616 20:33:05 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | Cycle 1(1): loaded http://localhost:45634/tests/dromaeo/cssquery-yui.html (next: http://localhost:45634/tests/dromaeo/cssquery-dojo.html) 20:36:08 INFO - PROCESS | 2137 | RSS: Main: 314408960 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | __start_tp_report 20:36:08 INFO - PROCESS | 2137 | _x_x_mozilla_page_load 20:36:08 INFO - PROCESS | 2137 | _x_x_mozilla_page_load_details 20:36:08 INFO - PROCESS | 2137 | |i|pagename|runs| 20:36:08 INFO - PROCESS | 2137 | |0;dojo.html;3045.6649768525463;3072.8002679825818;3073.5697002419665;3102.0073576455543;3118.220444888778;5110.770015349318;5176.870578235546;5200.583953283738;5218.843434696961;5229.163333866582;5267.920981185276;5288.682679039251;5370.892582148364;5400.459954004607;5428.891422171554;4735.408073990751;4742.881427964294;4789.760511974388;4812.975935120319;4842.982973575553;4856.198727210006;4878.829240976549;4927.2855435961765;4940.456549779521;4984.87537811554;4779.665423420362;4795.6882802618065;4855.344528488679;4910.950890491085;4914.70511769295;4746.833860814855;4757.976210118962;4785.521447855222;4838.056578967103;4851.878703032417;4731.455882573514;4741.359916411275;4742.478327383985;4797.2564252540915;4805.135075686375;5205.765740541685;5229.790808367661;5238.711870847102;5239.449857764956;5260.263563101169;5532.363778165482;5577.6653400796085;5593.188987596775;5656.547476201894;5661.433856614347;5631.662100273997;5688.288963879515;5708.20085188074;5759.740811663443;5770.596058275923;5286.97356513215;5290;5290.60320475962;5297.920531192035;5305.814296499604;5605.859853503695;5605.990921634145;5750.568707346965;5752.741126649311;5808;5514.145307477347;5520.503154716095;5520.613557051048;5524.475174858382;5526.115821468546;5543;5560.86097847549;5569.248151499496;5577.804776832872;5593.496585307261;5594.328680558359;5597.496225339739;5601.187827764951;5603.271574695345;5604.963081829875;5587.217789509472;5587.916181257285;5591.440855914376;5592.105263157875;5602.38373778884;4330.047389574289;4338.82644694215;4340;4342.32693932441;4359.215341238608;3893.902652433712;3914.2171565686976;3931.233409485123;3953.3872249801325;3956.5252169739815;5382.0043291991105;5390.787072908564;5391.946080539223;5397.27136836522;5397.568194544506;5676.943230567724;5690.77236910519;5691.459311365414;5700;5734.713264336767;5269.367675878842;5301.098813201765;5308.124159513714;5354.277172581729;5401.351837779492;4814.398200224972;4823.155947709137;4825.227963525819;4852.781624826821;4865.732384718804;5897.852553686193;5902.380250073767;5909.172715819789;5915.3788852170765;5959.910601340984;5565.777368905198;5613.101903695389;5622.3815380307315;5704.429557044263;5707.514861236841;5850.7074646266665;5894.263217097863;5897.144913987534;5937.406259374028;5939.851503712442;5582.916256256078;5585.636933599303;5586.441355864381;5587.078132108157;5591.161325801162;5579.107342825291;5607.074832652566;5679.204911312502;5686.317641883001;5693.629914055573;5549.112142057252;5567.331920169606;5597.972010140086;5627.943720562906;5675.716214189357;5505.9724701376235;5547.7780888764;5548.3619383769765;5553.555715542666;5598.972005139948;5281.60387970896;5458;5516.365617953987;5519.061759500814;5565.5825813063375;5419.810306639249;5514.558835293247;5549.084401073777;5581.023320918742;5661.065924122474;5489.58828087903;5491.670499769867;5497.257870187634;5500.4499550044675;5502.559795216293 20:36:08 INFO - PROCESS | 2137 | |1;ext.html;552.7475075353582;579.3713820504753;590.622001918772;631.198378059864;648.2253706820352;32746.181345466375;43421;44087.77956110215;44330.778346108265;47236.52763472373;44264;44314.77842610791;44470.777646111805;44729;44746.32880506787;7945.920540794605;8589.91410085899;8740.781480463007;8746.781330466725;8749.431286966359;7962.323202527792;9907.900920990805;10131;10131.79736405275;10408;10390.428526431044;10451.425171615558;10457.372557646566;10463.424511651856;10467.110295624881;10345.948270258637;10381.377117372982;10387.636432724894;10401.84397234042;10418.583256669725;10128.746781330452;10141.543630536644;10229.130523905476;10232.130268927076;10236.078752912199;41734.1653166938;41804.79097604523;41894;41909.58090419087;42018.789906050275;3457.844397002141;3545.0782796472845;3554.893353199382;3559.5906470755936;3586.3544561978833;31202.375952481052;31351.686483135334;31596.684033159832;32655.6734432655;33348.6665133348;4035.1929614077007;4038.1318016626274;4127.484064491939;4192.895177620553;4332;311468;314059;314462.42768786236;314850;315524;59460.40539594635;59621.70189149113;59932.40067599355;59945.40054599485;60245.39754602398;62218;62393.37606623876;62406.687966559875;62428.68785656043;62443;14856;15050.172240526657;15054.096754194783;15055.397784088733;15057.623559411102;10323.63867264642;10596.682099537025;10969.23215374916;11309.773804523864;11409.771804563863;17958.551036223937;18186;18188.1815318311;18199.909000455176;18206.18072186755;5720.742566584514;5982.641041537521;6052.939470605325;6059.272887253558;6068.8786224276155;955.4315182466421;985.605757696927;1018.2922868606396;1046.9530469530469;1116.5757012335423;31912;32222.19444513859;32364.35271294561;32917;33071.66928330734;1015.735908663738;1075.9139268858473;1085.56577369053;1142.2917790969652;1157.704785279765;16157.676846463004;17146.742798858028;17166.39917602878;17166.570835729206;17177.570560735832;442484;444454;444902;446636;745174;52376.73811630918;55845.44154558402;57274.42725572774;57341;62800.68599656972;1277.7189018415934;1310.3382791690326;1326.104879206551;1344.7444985452928;1346.0443085409274;30299.52076677387;32624.673753262163;32859.50710739389;33218.33563328672;33457.163570910925;50935;51722.48277517177;51759.74120129375;51762;51777;388560;389928;390406;390572;391731;25122.12072577451;40920;41109.1778164441;41359.793201033805;41360;42215.155696885275;45018.77490612657;45142.548574513836;45154.54845451503;45204.54795452135;38510.42234366543;38602.42096368501;38924;39824.800875996596;40308.39537407;37357.813210933775;38578.6142138575;38666.226675466896;39286.60713392829;39749.205015898944;39234.803825980685;40303.39544906888;40459.59540404676;40497.79751101226;40537.79731101326;31760.523592145673;34198.31603367869;34373.65626343704;34813.90764083888;35104.29791404107;26531.336716581467;27188.592171117052;27320.59019114675;27379.31551711223;27399.726002739717 20:36:08 INFO - PROCESS | 2137 | |2;jquery.html;376.1761741785491;377.9225258821942;385.545056832937;390.47871092092055;407.9082206503537;13978.37131731541;15728.60678483037;18385.724214136797;18719.4384168475;18734.906325468353;16964.236609352578;17096.572585685364;17178.656426871454;17278.654426911453;17335;4201.978990105045;5468.7539060742265;5610.24261724666;5636.098224284095;5663.886722265553;4303.591158839905;6011.398860113975;6188.721507532149;6262.6868656566985;6379.968100159493;6101.145839582462;6294.402031806971;6303.527235457336;6307.1485349477925;6332.461740752042;6306.180196574439;6349.8412539686415;6354.046892966046;6354.364563543655;6354.379633193506;5386;5475.4524547545325;5494.120940649517;5500;5574.5351653215375;22444.66333005006;22529.774702252933;22869.344986840177;23024.53950920972;23450;2257.853239539441;2441.865697386643;2499.8125140614343;2517.420993171581;2666.2401215653445;14059.22674252926;14187.148771073666;14217.21805300708;14386.784198237036;14732.410703571844;1833.4041436533046;1935.9612807743765;1938.0794122791701;1948.8635795494322;1960.4216756056996;71572;72900.63549682217;77219.61390193013;77406.22593774102;77752.61123694346;10804.459777011276;10880.891191087987;11112.611058612909;11248;11410.828837567444;15883.014623172103;15885.126318052618;15893;15899.125548094966;15920.52238432849;7522.586257755766;7708.344790692742;7778.688852445952;7844.019497562805;7861.292483676382;6907.412869906014;6908.585484870924;7123.180834204029;7126.857462850714;7141.500094993301;4706.3411122442885;4722;4739.194336962656;4767.6662633615315;4779.187538118528;1814.7187185986193;1889.3859495663964;1954.140178321534;2034.104993802722;2047.64166270902;728.5155371677881;868.6004437958484;959.6305422412421;972.8978457262027;1007.6976906927893;18294.817051829577;19225.13486893065;19326.226950922086;19375.806241937684;19511.0495995364;984.3552473130111;987.7086259553449;987.7925635616457;992.4690290694443;997.870276864003;10461.476926153662;10803.243772935975;10812.459377031117;10825.188110891715;10837.729056773644;449097;450235;451668;456228;456600;97610;99073;99906.5004674972;100111;104442.47778761058;1008.5007921079113;1035.492608621785;1039.5374058543875;1095.4905968724581;1096.5997410944947;17063.48809535716;17344.934322790727;17647.38234161798;17758.46724598264;18128.45614631563;39995.40006899841;40751.59248407478;40854.79572602237;40953;42392;56244;56862.7156864213;57359;58640.413595863494;58869.70565147147;8313.12712165214;8323.458975166595;8537.359698022798;8605.95697021511;8609.741707748777;12404.131710780159;12729.55446559366;12873.970082393198;12938.676533086747;13010.414531346301;11120.555177792798;11237.7752444949;11255.324680519196;11264.155188360743;11752.764944700886;11341.26281791681;11460.770784584094;11528.538858445903;11532.25040372373;11769.646910592695;10729.892701073202;11512.30926144434;11574.131940104357;11614.477348519506;11873.465694043616;8248.216419440143;8317.293030092509;8348.248657620843;8506.702265420681;8676.826463470821;8303.584820759059;10528.315659482108;10559.260851740304;10736.946315268686;11383.772324553296 20:36:08 INFO - PROCESS | 2137 | |3;mootools.html;1409.4714482069228;1447.565730280916;1548.5044785668583;1675.2210222246667;1700.4473546097522;7227.385672217868;7439.293267139627;7706.922930770691;8143.33061722347;8148.103708592058;7677.3858091352695;7951.562664053475;7953.642086106124;8101.959490202556;8113.756587302376;2243.4054975431523;2285.5543169082034;2293.289080385078;2294.3919861236786;2296.701428814252;2008.6786114221727;2066.7209926659934;2251.324602619216;2296.3455415206668;2316.363000174957;2219.7447293561204;2272.556851413975;2277.806386457145;2289.290320000797;2303.7581053989343;2239.283429302624;2248.921287754929;2270.182734215681;2272.3410211038777;2284.097781376355;2252.673362362448;2264.9886750566307;2284.588774020676;2288.6681431192383;2314.0975019742314;8038.8794168087525;8076;8127.268545830844;8128.796780080486;8137.674493020331;4963.776630051656;5091.261767043795;5091.41448733393;5095.541401273866;5103.157978933472;3017.109952563999;3095.1952492351925;3141.780075394723;3165.841707914618;3176.7299779518767;1017.5065093429681;1024.9692509224735;1964.7544056992876;1978.940631781049;1989.263972330247;10911.454427278604;10931.890681093246;10999.120070394349;11031.22781405302;11082.722931926766;4178.247915375262;4291.54938731435;4371.23503386906;4387.868363949086;4394.582514661102;8862.512561809033;8862.77843053916;8870.467771933705;8870.600822962982;8873.822523549492;4973;4979.128652485873;4986.276989836526;4988.8752781180765;4992.276119962585;4509.278515437514;4537.546245375502;4583.702059366131;4585.174668559691;4599.195140850338;3473.8610455581997;3523.823808809549;3531.9646803531637;3565.8395372208315;3577.3918433866306;1026.296986564206;1044.9634262800766;1115.4144074361025;1116.547798141754;1130.4178348150708;855.461059532503;969.5588507229194;972.9951350243346;979.1921664626711;1011.8785745710414;5174.327337446183;5217.6347655664495;5225.477452254744;5439.673619582759;5880.382559831241;921.318224513855;925.5233554719325;932.7668082979255;933.5518950903604;971.1987610221596;4020.135670830727;4077.6330130287824;4083.1425400666194;4132.214879172948;4141.461609990682;9710.708678739507;9916.851247231298;10026.749331266778;10027.849582256273;10051.949740251252;9245.491497967676;9436.90563094374;9651.083147101013;9652.75868103289;9967.152792012761;7145.535540189975;7283.053203083565;7503.624818759149;7516.210797866145;7656.846863062708;5494.36814766307;5593.692346920959;5641.210230567806;5695.174199740932;5808.767649293981;8830.91169088301;8956.910430895608;8991;9113.635454581743;9117.589708463267;9012.654210797857;9159.725208243763;9160.725178244664;9274.721758347261;9281.767955801159;7602.581857997863;7715.382769378549;7718.614069296625;7723.073231212291;7724.1889601593275;7630.465867389229;7651.61741912891;7707;7721.266479684421;7725.304722574995;6917.861642767217;6961.303869612998;7002.2647622000995;7026.332498412643;7162.46281528877;6867.725290988305;6996.475264355296;7001.50989430735;7030.15638123408;7037.261087585935;6413;6580.868382632225;6588.538802283986;6589.04458853454;6592.967035164794;4977.925331119964;5066.695998240118;5073.264376665289;5164.354455693038;5197.4282828889545;4582.839600613963;4800.495947925418;4854.271859221145;5085.77114029877;5254.763535640829 20:36:08 INFO - PROCESS | 2137 | |4;prototype.html;316.91443310306215;328.8421557652327;331.14564423786635;331.60538958639216;334.5633947698254;5347.6791392516425;5352.717825739413;5398.298221231239;5411.215373770801;5411.3506379234495;5050.802900738172;5080;5185.325907632012;5210.973945130269;5325.094733895237;4128.112455821998;4129.483814523184;4138.379243113534;4151.23202207591;4161.459010328653;4162.895927601804;4169.62473377396;4170.332746760519;4171.290880550314;4174.561671024544;3845.788766380062;3968.5237771467464;4113.629773320401;4126.91746165078;4143.0885205254945;4095.3037983542868;4104.8506418202805;4110.383442483621;4124.319487284594;4133.256013917508;3639.6542328478754;3654.926901461956;3655.2689462107683;3667.3032123896373;3671.375866102769;4996.4254110777365;5134.204198349262;5141.948580514222;5146.044503088802;5150.330457040561;4684.742339171345;4684.922467832418;4707.375955295418;4720.43354797423;4728.905421891577;5583.832485025456;5603.887922241533;5612.681019960306;5624.072028115356;5625.6905870177125;3761.172542040774;3916.608339166089;3983.063979964706;3985.143194213229;3990.7206495545324;5747.339056008571;5756.3667996519935;5766.394528574481;5793.420657934173;5803.477687008147;4043.292423825819;4043.5147782266317;4043.696722745806;4067.918641627151;4073.3075377185355;3902.5755599206086;4078.510578730512;4121.9381709274385;4129.132882094794;4131.8967025824595;4681.274402467623;4688.33564084745;4693.249080147229;4711.905761884743;4720.740359280273;5568.832935011957;5572.01896985111;5572.665640061609;5577.414371491016;5613.887722245532;1322.2397121655088;1370.712150448397;1373.9313034348443;1379.1380387257964;1389.6456403617215;893.2496702769705;900.8108297257518;929.1451864284874;930.4882314727008;948.9430634161972;5209.4009188943;5215.191645294985;5227.065985585357;5244.868878278074;5277.23480095384;5500.3949565547755;5509.531689806411;5530.391656917735;5545.584081193927;5546.334439867161;5085.338905942203;5114.33513643224;5169.198774190007;5179.695035709074;5281.894362112736;2886.7194916016533;2887.7112288771373;2935.2661834541364;2955.837428941386;2956.339294634803;5373.516383525502;5744.827655170351;5744.978515039489;5767.682777447197;5782.566307526952;5461.890762184734;5468.890622187534;5505.944940550623;5510.6969116698165;5525.4474552544425;5597.3003374578175;5619.241402410704;5640.210370548127;5655.604107712421;5706.087026075974;5184.896302074013;5408.432114627907;5435.619506634498;5465.562754979513;5472.972635136799;5406.243125962447;5430.891382172414;5435.945640543702;5459.344878614433;5460.34475862899;5665.915011274917;5683.658980461186;5689.6956732523395;5692.459216374438;5695.658260504383;3764.265968136187;3836.501254836965;3865.806709664449;3892.377219644843;3908.159745654698;5401.648892821954;5407.540359069523;5448.509634132947;5458.506555804368;5461.290032295776;5359.464053594609;5361.177199752103;5367.5169234769055;5375.435579264121;5380.300560927054;5128.358955130609;5203.791848326025;5273.6572122811895;5289.9471005291;5384.896096300327;5198.272241886215;5224.682326722483;5247.685138891526;5250.684958902478;5300.920486192787;5498.9002199559445;5506.972465137648;5595.761695321904;5638.379778224474;5644.52021578154;5467.261919640957;5478.945395476696;5498.890022199454;5499.560035197094;5510.834674959758 20:36:08 INFO - PROCESS | 2137 | |5;yui.html;751.4401770680844;768.8500742355242;769.4921351907742;799.8040480082379;813.8715472492137;15085.170315632637;15898.682026359465;15900.602484937883;15915.761263581055;16061.83938160618;15534.456294029711;15701.528954131365;15704.37182512701;15704.450344237954;15708.528744137666;655.12540758088;677.288846710954;755.7128291249345;769.542122437149;789.4197764642983;771.4329967473902;780.8516381887423;782.6830133795822;783.8275579372557;784.4391260248921;849.1041950741965;850.4387104511025;850.8978922529305;851.0255757158051;851.2083762101244;691.0429055757761;710.7583421636616;797.7646594254651;840.6385254340631;842.204117109332;596.4751019103205;600.1837500998638;601.3205078261536;601.3986013986014;606.1029676079384;13975.09161904473;14099.78850317246;14108.576742697736;14111.294435278194;14135.081219720685;14329.71340573193;14368;14517;14556.70886582273;14557.344919478648;12785.552505662257;12846.550370737074;12934.41795119222;13099.41052652633;13825.585232443043;4273.401723758645;4292.098659281554;4372.366006929009;4385.890352741175;4419.580139886705;89771.55114224387;90878;90911.54544227303;91139.54430227872;91344.54327728385;11624;11643.41782910851;11707.2975621463;11718.648440546796;11728.530858765724;22415;22610.66084008741;22628.321150365515;22738.658920116213;22865.314040578476;7759.689612415553;7867.370610351272;7978.281954623995;8156.265936065783;8395.118512556093;4459.710118842265;4509.729416235036;4512.300593408027;4577.679562430598;4856.295837103671;3288.4245257080393;3356.2951780126446;3359.227377703093;3363.2768954674375;3414.914627134292;590.5718354193176;596.9283685957625;609.3845216331537;615.9784407545715;627.5042716254109;542.8235823357425;595.1994567306965;648.419664400359;654.7839213059679;670.0050425118695;9201.861972070425;9783.168430683472;9785.461799601091;9796.118349348593;9821.116099551075;551.9305181904223;646.4085361893908;658.3712554510431;674.4570620650424;714.9428045756327;4623.86128416148;4910.8772280693265;4942.184539550934;4952.628552858478;4969.2546118081855;89211;89283.55358223167;89649;90795.54602227079;92958.53520732353;73196;74537;75279;76263.61868190624;76908.61545692236;67452;67505.32494675089;68515.65742171257;69029.65485172543;73611.89582156272;9267.44395336282;9493.762655933657;9570.712878613653;9571.18644915162;9630.32587718853;46372.45101959243;47650.52349476461;47710.522894770605;48378.75810620924;49093.50906491032;63282.68358658177;63494.36505634885;65024;65138;66496.66751666211;11735.119866010255;11759.193723644936;11909.52361905514;12148;12255.632331030083;12785.16896401731;12828.807567886302;12951.222926624432;13017.609471715863;13036.934815325863;12582.307973061203;12842.954469342127;12883.227006379648;13019.414126364525;13594.912407007614;11699;12069.283673051928;12452.68868278264;12634.557790477289;12989.220646761225;12517.24896506176;13059.673508162372;13232.976899521249;13236.66908327261;13384.263865487492;10000.2999790014;10390;10436.739081523023;10490.60073355268;10606.840897386392;9142.542872856464;10662.626808061681;10781.514831832743;10935;10938.343699378063 20:36:08 INFO - PROCESS | 2137 | __end_tp_report 20:36:08 INFO - PROCESS | 2137 | __start_cc_report 20:36:08 INFO - PROCESS | 2137 | _x_x_mozilla_cycle_collect,1346 20:36:08 INFO - PROCESS | 2137 | __end_cc_report 20:36:08 INFO - PROCESS | 2137 | __startTimestamp1480480568525__endTimestamp 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | ------- Summary: start ------- 20:36:08 INFO - PROCESS | 2137 | Number of tests: 6 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#0] dojo.html Cycles:165 Average:5256.22 Median:5502.56 stddev:587.89 (10.7%) stddev-sans-first:563.54 20:36:08 INFO - PROCESS | 2137 | Values: 3045.7 3072.8 3073.6 3102.0 3118.2 5110.8 5176.9 5200.6 5218.8 5229.2 5267.9 5288.7 5370.9 5400.5 5428.9 4735.4 4742.9 4789.8 4813.0 4843.0 4856.2 4878.8 4927.3 4940.5 4984.9 4779.7 4795.7 4855.3 4911.0 4914.7 4746.8 4758.0 4785.5 4838.1 4851.9 4731.5 4741.4 4742.5 4797.3 4805.1 5205.8 5229.8 5238.7 5239.4 5260.3 5532.4 5577.7 5593.2 5656.5 5661.4 5631.7 5688.3 5708.2 5759.7 5770.6 5287.0 5290.0 5290.6 5297.9 5305.8 5605.9 5606.0 5750.6 5752.7 5808.0 5514.1 5520.5 5520.6 5524.5 5526.1 5543.0 5560.9 5569.2 5577.8 5593.5 5594.3 5597.5 5601.2 5603.3 5605.0 5587.2 5587.9 5591.4 5592.1 5602.4 4330.0 4338.8 4340.0 4342.3 4359.2 3893.9 3914.2 3931.2 3953.4 3956.5 5382.0 5390.8 5391.9 5397.3 5397.6 5676.9 5690.8 5691.5 5700.0 5734.7 5269.4 5301.1 5308.1 5354.3 5401.4 4814.4 4823.2 4825.2 4852.8 4865.7 5897.9 5902.4 5909.2 5915.4 5959.9 5565.8 5613.1 5622.4 5704.4 5707.5 5850.7 5894.3 5897.1 5937.4 5939.9 5582.9 5585.6 5586.4 5587.1 5591.2 5579.1 5607.1 5679.2 5686.3 5693.6 5549.1 5567.3 5598.0 5627.9 5675.7 5506.0 5547.8 5548.4 5553.6 5599.0 5281.6 5458.0 5516.4 5519.1 5565.6 5419.8 5514.6 5549.1 5581.0 5661.1 5489.6 5491.7 5497.3 5500.4 5502.6 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#1] ext.html Cycles:180 Average:57102.40 Median:38590.52 stddev:110408.43 (286.1%) stddev-sans-first:110636.52 20:36:08 INFO - PROCESS | 2137 | Values: 552.7 579.4 590.6 631.2 648.2 32746.2 43421.0 44087.8 44330.8 47236.5 44264.0 44314.8 44470.8 44729.0 44746.3 7945.9 8589.9 8740.8 8746.8 8749.4 7962.3 9907.9 10131.0 10131.8 10408.0 10390.4 10451.4 10457.4 10463.4 10467.1 10345.9 10381.4 10387.6 10401.8 10418.6 10128.7 10141.5 10229.1 10232.1 10236.1 41734.2 41804.8 41894.0 41909.6 42018.8 3457.8 3545.1 3554.9 3559.6 3586.4 31202.4 31351.7 31596.7 32655.7 33348.7 4035.2 4038.1 4127.5 4192.9 4332.0 311468.0 314059.0 314462.4 314850.0 315524.0 59460.4 59621.7 59932.4 59945.4 60245.4 62218.0 62393.4 62406.7 62428.7 62443.0 14856.0 15050.2 15054.1 15055.4 15057.6 10323.6 10596.7 10969.2 11309.8 11409.8 17958.6 18186.0 18188.2 18199.9 18206.2 5720.7 5982.6 6052.9 6059.3 6068.9 955.4 985.6 1018.3 1047.0 1116.6 31912.0 32222.2 32364.4 32917.0 33071.7 1015.7 1075.9 1085.6 1142.3 1157.7 16157.7 17146.7 17166.4 17166.6 17177.6 442484.0 444454.0 444902.0 446636.0 745174.0 52376.7 55845.4 57274.4 57341.0 62800.7 1277.7 1310.3 1326.1 1344.7 1346.0 30299.5 32624.7 32859.5 33218.3 33457.2 50935.0 51722.5 51759.7 51762.0 51777.0 388560.0 389928.0 390406.0 390572.0 391731.0 25122.1 40920.0 41109.2 41359.8 41360.0 42215.2 45018.8 45142.5 45154.5 45204.5 38510.4 38602.4 38924.0 39824.8 40308.4 37357.8 38578.6 38666.2 39286.6 39749.2 39234.8 40303.4 40459.6 40497.8 40537.8 31760.5 34198.3 34373.7 34813.9 35104.3 26531.3 27188.6 27320.6 27379.3 27399.7 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#2] jquery.html Cycles:180 Average:27934.53 Median:2591.83 stddev:74979.74 (2892.9%) stddev-sans-first:75161.52 20:36:08 INFO - PROCESS | 2137 | Values: 376.2 377.9 385.5 390.5 407.9 13978.4 15728.6 18385.7 18719.4 18734.9 16964.2 17096.6 17178.7 17278.7 17335.0 4202.0 5468.8 5610.2 5636.1 5663.9 4303.6 6011.4 6188.7 6262.7 6380.0 6101.1 6294.4 6303.5 6307.1 6332.5 6306.2 6349.8 6354.0 6354.4 6354.4 5386.0 5475.5 5494.1 5500.0 5574.5 22444.7 22529.8 22869.3 23024.5 23450.0 2257.9 2441.9 2499.8 2517.4 2666.2 14059.2 14187.1 14217.2 14386.8 14732.4 1833.4 1936.0 1938.1 1948.9 1960.4 71572.0 72900.6 77219.6 77406.2 77752.6 10804.5 10880.9 11112.6 11248.0 11410.8 15883.0 15885.1 15893.0 15899.1 15920.5 7522.6 7708.3 7778.7 7844.0 7861.3 6907.4 6908.6 7123.2 7126.9 7141.5 4706.3 4722.0 4739.2 4767.7 4779.2 1814.7 1889.4 1954.1 2034.1 2047.6 728.5 868.6 959.6 972.9 1007.7 18294.8 19225.1 19326.2 19375.8 19511.0 984.4 987.7 987.8 992.5 997.9 10461.5 10803.2 10812.5 10825.2 10837.7 449097.0 450235.0 451668.0 456228.0 456600.0 97610.0 99073.0 99906.5 100111.0 104442.5 1008.5 1035.5 1039.5 1095.5 1096.6 17063.5 17344.9 17647.4 17758.5 18128.5 39995.4 40751.6 40854.8 40953.0 42392.0 56244.0 56862.7 57359.0 58640.4 58869.7 8313.1 8323.5 8537.4 8606.0 8609.7 12404.1 12729.6 12874.0 12938.7 13010.4 11120.6 11237.8 11255.3 11264.2 11752.8 11341.3 11460.8 11528.5 11532.3 11769.6 10729.9 11512.3 11574.1 11614.5 11873.5 8248.2 8317.3 8348.2 8506.7 8676.8 8303.6 10528.3 10559.3 10736.9 11383.8 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#3] mootools.html Cycles:180 Average:5357.80 Median:5133.76 stddev:2892.82 (56.3%) stddev-sans-first:2885.72 20:36:08 INFO - PROCESS | 2137 | Values: 1409.5 1447.6 1548.5 1675.2 1700.4 7227.4 7439.3 7706.9 8143.3 8148.1 7677.4 7951.6 7953.6 8102.0 8113.8 2243.4 2285.6 2293.3 2294.4 2296.7 2008.7 2066.7 2251.3 2296.3 2316.4 2219.7 2272.6 2277.8 2289.3 2303.8 2239.3 2248.9 2270.2 2272.3 2284.1 2252.7 2265.0 2284.6 2288.7 2314.1 8038.9 8076.0 8127.3 8128.8 8137.7 4963.8 5091.3 5091.4 5095.5 5103.2 3017.1 3095.2 3141.8 3165.8 3176.7 1017.5 1025.0 1964.8 1978.9 1989.3 10911.5 10931.9 10999.1 11031.2 11082.7 4178.2 4291.5 4371.2 4387.9 4394.6 8862.5 8862.8 8870.5 8870.6 8873.8 4973.0 4979.1 4986.3 4988.9 4992.3 4509.3 4537.5 4583.7 4585.2 4599.2 3473.9 3523.8 3532.0 3565.8 3577.4 1026.3 1045.0 1115.4 1116.5 1130.4 855.5 969.6 973.0 979.2 1011.9 5174.3 5217.6 5225.5 5439.7 5880.4 921.3 925.5 932.8 933.6 971.2 4020.1 4077.6 4083.1 4132.2 4141.5 9710.7 9916.9 10026.7 10027.8 10051.9 9245.5 9436.9 9651.1 9652.8 9967.2 7145.5 7283.1 7503.6 7516.2 7656.8 5494.4 5593.7 5641.2 5695.2 5808.8 8830.9 8956.9 8991.0 9113.6 9117.6 9012.7 9159.7 9160.7 9274.7 9281.8 7602.6 7715.4 7718.6 7723.1 7724.2 7630.5 7651.6 7707.0 7721.3 7725.3 6917.9 6961.3 7002.3 7026.3 7162.5 6867.7 6996.5 7001.5 7030.2 7037.3 6413.0 6580.9 6588.5 6589.0 6593.0 4977.9 5066.7 5073.3 5164.4 5197.4 4582.8 4800.5 4854.3 5085.8 5254.8 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#4] prototype.html Cycles:180 Average:4590.19 Median:5235.97 stddev:1339.00 (25.6%) stddev-sans-first:1303.78 20:36:08 INFO - PROCESS | 2137 | Values: 316.9 328.8 331.1 331.6 334.6 5347.7 5352.7 5398.3 5411.2 5411.4 5050.8 5080.0 5185.3 5211.0 5325.1 4128.1 4129.5 4138.4 4151.2 4161.5 4162.9 4169.6 4170.3 4171.3 4174.6 3845.8 3968.5 4113.6 4126.9 4143.1 4095.3 4104.9 4110.4 4124.3 4133.3 3639.7 3654.9 3655.3 3667.3 3671.4 4996.4 5134.2 5141.9 5146.0 5150.3 4684.7 4684.9 4707.4 4720.4 4728.9 5583.8 5603.9 5612.7 5624.1 5625.7 3761.2 3916.6 3983.1 3985.1 3990.7 5747.3 5756.4 5766.4 5793.4 5803.5 4043.3 4043.5 4043.7 4067.9 4073.3 3902.6 4078.5 4121.9 4129.1 4131.9 4681.3 4688.3 4693.2 4711.9 4720.7 5568.8 5572.0 5572.7 5577.4 5613.9 1322.2 1370.7 1373.9 1379.1 1389.6 893.2 900.8 929.1 930.5 948.9 5209.4 5215.2 5227.1 5244.9 5277.2 5500.4 5509.5 5530.4 5545.6 5546.3 5085.3 5114.3 5169.2 5179.7 5281.9 2886.7 2887.7 2935.3 2955.8 2956.3 5373.5 5744.8 5745.0 5767.7 5782.6 5461.9 5468.9 5505.9 5510.7 5525.4 5597.3 5619.2 5640.2 5655.6 5706.1 5184.9 5408.4 5435.6 5465.6 5473.0 5406.2 5430.9 5435.9 5459.3 5460.3 5665.9 5683.7 5689.7 5692.5 5695.7 3764.3 3836.5 3865.8 3892.4 3908.2 5401.6 5407.5 5448.5 5458.5 5461.3 5359.5 5361.2 5367.5 5375.4 5380.3 5128.4 5203.8 5273.7 5289.9 5384.9 5198.3 5224.7 5247.7 5250.7 5300.9 5498.9 5507.0 5595.8 5638.4 5644.5 5467.3 5478.9 5498.9 5499.6 5510.8 20:36:08 INFO - PROCESS | 2137 | 20:36:08 INFO - PROCESS | 2137 | [#5] yui.html Cycles:180 Average:18905.27 Median:4926.53 stddev:25652.73 (520.7%) stddev-sans-first:25688.47 20:36:08 INFO - PROCESS | 2137 | Values: 751.4 768.9 769.5 799.8 813.9 15085.2 15898.7 15900.6 15915.8 16061.8 15534.5 15701.5 15704.4 15704.5 15708.5 655.1 677.3 755.7 769.5 789.4 771.4 780.9 782.7 783.8 784.4 849.1 850.4 850.9 851.0 851.2 691.0 710.8 797.8 840.6 842.2 596.5 600.2 601.3 601.4 606.1 13975.1 14099.8 14108.6 14111.3 14135.1 14329.7 14368.0 14517.0 14556.7 14557.3 12785.6 12846.6 12934.4 13099.4 13825.6 4273.4 4292.1 4372.4 4385.9 4419.6 89771.6 90878.0 90911.5 91139.5 91344.5 11624.0 11643.4 11707.3 11718.6 11728.5 22415.0 22610.7 22628.3 22738.7 22865.3 7759.7 7867.4 7978.3 8156.3 8395.1 4459.7 4509.7 4512.3 4577.7 4856.3 3288.4 3356.3 3359.2 3363.3 3414.9 590.6 596.9 609.4 616.0 627.5 542.8 595.2 648.4 654.8 670.0 9201.9 9783.2 9785.5 9796.1 9821.1 551.9 646.4 658.4 674.5 714.9 4623.9 4910.9 4942.2 4952.6 4969.3 89211.0 89283.6 89649.0 90795.5 92958.5 73196.0 74537.0 75279.0 76263.6 76908.6 67452.0 67505.3 68515.7 69029.7 73611.9 9267.4 9493.8 9570.7 9571.2 9630.3 46372.5 47650.5 47710.5 48378.8 49093.5 63282.7 63494.4 65024.0 65138.0 66496.7 11735.1 11759.2 11909.5 12148.0 12255.6 12785.2 12828.8 12951.2 13017.6 13036.9 12582.3 12843.0 12883.2 13019.4 13594.9 11699.0 12069.3 12452.7 12634.6 12989.2 12517.2 13059.7 13233.0 13236.7 13384.3 10000.3 10390.0 10436.7 10490.6 10606.8 9142.5 10662.6 10781.5 10935.0 10938.3 20:36:08 INFO - PROCESS | 2137 | -------- Summary: end -------- 20:36:08 INFO - PROCESS | 2137 | 20:36:09 INFO - TEST-INFO | 2137: exit 0 20:36:09 INFO - TEST-OK | dromaeo_css | took 1118406ms 20:36:09 INFO - TEST-START | kraken 20:36:09 INFO - operating with platform_type : linux_ 20:36:09 INFO - Initialising browser for kraken test... 20:36:09 INFO - TEST-INFO | started process 2513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJoxnw/profile http://localhost:45634/getInfo.html) 20:36:13 INFO - PROCESS | 2513 | __metrics Screen width/height:1600/1200 20:36:13 INFO - PROCESS | 2513 | colorDepth:24 20:36:13 INFO - PROCESS | 2513 | Browser inner width/height: 1024/697 20:36:13 INFO - PROCESS | 2513 | __metrics 20:36:14 INFO - PROCESS | 2513 | 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" 20:36:14 INFO - PROCESS | 2513 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:36:15 INFO - PROCESS | 2513 | console.error: PushService: 20:36:15 INFO - PROCESS | 2513 | stateChangeProcessEnqueue: Error transitioning state 20:36:15 INFO - PROCESS | 2513 | UnknownError 20:36:15 INFO - TEST-INFO | 2513: exit 0 20:36:15 INFO - Browser initialized. 20:36:15 INFO - Running cycle 1/1 for kraken test... 20:36:15 INFO - TEST-INFO | started process 2618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgJoxnw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:27 INFO - PROCESS | 2618 | RSS: Main: 173342720 20:36:27 INFO - PROCESS | 2618 | 20:36:30 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45634/tests/kraken/driver.html?testName=audio-beat-detection) 20:36:31 INFO - PROCESS | 2618 | RSS: Main: 230572032 20:36:31 INFO - PROCESS | 2618 | 20:36:33 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45634/tests/kraken/driver.html?testName=audio-dft) 20:36:34 INFO - PROCESS | 2618 | RSS: Main: 216907776 20:36:34 INFO - PROCESS | 2618 | 20:36:37 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45634/tests/kraken/driver.html?testName=audio-fft) 20:36:38 INFO - PROCESS | 2618 | RSS: Main: 298749952 20:36:38 INFO - PROCESS | 2618 | 20:36:40 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45634/tests/kraken/driver.html?testName=audio-oscillator) 20:36:40 INFO - PROCESS | 2618 | RSS: Main: 209362944 20:36:40 INFO - PROCESS | 2618 | 20:36:43 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45634/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:36:43 INFO - PROCESS | 2618 | RSS: Main: 213925888 20:36:43 INFO - PROCESS | 2618 | 20:36:46 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45634/tests/kraken/driver.html?testName=imaging-darkroom) 20:36:47 INFO - PROCESS | 2618 | RSS: Main: 252813312 20:36:47 INFO - PROCESS | 2618 | 20:36:50 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45634/tests/kraken/driver.html?testName=imaging-desaturate) 20:36:51 INFO - PROCESS | 2618 | RSS: Main: 253341696 20:36:51 INFO - PROCESS | 2618 | 20:36:53 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45634/tests/kraken/driver.html?testName=json-parse-financial) 20:36:54 INFO - PROCESS | 2618 | RSS: Main: 252243968 20:36:54 INFO - PROCESS | 2618 | 20:36:56 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45634/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:36:56 INFO - PROCESS | 2618 | RSS: Main: 214192128 20:36:56 INFO - PROCESS | 2618 | 20:36:58 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:36:59 INFO - PROCESS | 2618 | RSS: Main: 221413376 20:36:59 INFO - PROCESS | 2618 | 20:37:01 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:37:01 INFO - PROCESS | 2618 | RSS: Main: 229969920 20:37:01 INFO - PROCESS | 2618 | 20:37:04 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:37:05 INFO - PROCESS | 2618 | RSS: Main: 238247936 20:37:05 INFO - PROCESS | 2618 | 20:37:07 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:37:08 INFO - PROCESS | 2618 | RSS: Main: 235941888 20:37:08 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | Cycle 1(1): loaded http://localhost:45634/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45634/tests/kraken/driver.html?testName=ai-astar) 20:37:10 INFO - PROCESS | 2618 | RSS: Main: 287854592 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | __start_tp_report 20:37:10 INFO - PROCESS | 2618 | _x_x_mozilla_page_load 20:37:10 INFO - PROCESS | 2618 | _x_x_mozilla_page_load_details 20:37:10 INFO - PROCESS | 2618 | |i|pagename|runs| 20:37:10 INFO - PROCESS | 2618 | |0;ai-astar;110;112;112;113;114;112;111;112;110;112 20:37:10 INFO - PROCESS | 2618 | |1;audio-beat-detection;148;123;128;159;125;124;126;123;141;125 20:37:10 INFO - PROCESS | 2618 | |2;audio-dft;161;162;162;161;163;163;163;162;161;160 20:37:10 INFO - PROCESS | 2618 | |3;audio-fft;76;75;80;114;77;77;77;75;96;75 20:37:10 INFO - PROCESS | 2618 | |4;audio-oscillator;82;82;105;82;82;83;96;83;82;102 20:37:10 INFO - PROCESS | 2618 | |5;imaging-gaussian-blur;119;117;116;117;117;117;117;117;117;118 20:37:10 INFO - PROCESS | 2618 | |6;imaging-darkroom;146;147;146;147;146;148;147;147;146;146 20:37:10 INFO - PROCESS | 2618 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 20:37:10 INFO - PROCESS | 2618 | |8;json-parse-financial;54;52;53;52;52;61;53;53;53;53 20:37:10 INFO - PROCESS | 2618 | |9;json-stringify-tinderbox;66;62;62;62;62;62;62;62;63;63 20:37:10 INFO - PROCESS | 2618 | |10;stanford-crypto-aes;81;80;86;81;81;79;80;79;85;80 20:37:10 INFO - PROCESS | 2618 | |11;stanford-crypto-ccm;135;131;136;132;137;140;136;129;188;132 20:37:10 INFO - PROCESS | 2618 | |12;stanford-crypto-pbkdf2;173;166;166;164;165;165;166;166;166;164 20:37:10 INFO - PROCESS | 2618 | |13;stanford-crypto-sha256-iterative;66;67;72;67;66;66;67;66;68;64 20:37:10 INFO - PROCESS | 2618 | __end_tp_report 20:37:10 INFO - PROCESS | 2618 | __start_cc_report 20:37:10 INFO - PROCESS | 2618 | _x_x_mozilla_cycle_collect,1694 20:37:10 INFO - PROCESS | 2618 | __end_cc_report 20:37:10 INFO - PROCESS | 2618 | __startTimestamp1480480630422__endTimestamp 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | ------- Summary: start ------- 20:37:10 INFO - PROCESS | 2618 | Number of tests: 14 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.23 (1.1%) stddev-sans-first:1.12 20:37:10 INFO - PROCESS | 2618 | Values: 110.0 112.0 112.0 113.0 114.0 112.0 111.0 112.0 110.0 112.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:127.00 stddev:12.66 (10.0%) stddev-sans-first:12.06 20:37:10 INFO - PROCESS | 2618 | Values: 148.0 123.0 128.0 159.0 125.0 124.0 126.0 123.0 141.0 125.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 20:37:10 INFO - PROCESS | 2618 | Values: 161.0 162.0 162.0 161.0 163.0 163.0 163.0 162.0 161.0 160.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#3] audio-fft Cycles:10 Average:82.20 Median:77.00 stddev:12.83 (16.7%) stddev-sans-first:13.41 20:37:10 INFO - PROCESS | 2618 | Values: 76.0 75.0 80.0 114.0 77.0 77.0 77.0 75.0 96.0 75.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:82.00 stddev:9.30 (11.3%) stddev-sans-first:9.62 20:37:10 INFO - PROCESS | 2618 | Values: 82.0 82.0 105.0 82.0 82.0 83.0 96.0 83.0 82.0 102.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.50 20:37:10 INFO - PROCESS | 2618 | Values: 119.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 20:37:10 INFO - PROCESS | 2618 | Values: 146.0 147.0 146.0 147.0 146.0 148.0 147.0 147.0 146.0 146.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:37:10 INFO - PROCESS | 2618 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.00 stddev:2.67 (5.0%) stddev-sans-first:2.83 20:37:10 INFO - PROCESS | 2618 | Values: 54.0 52.0 53.0 52.0 52.0 61.0 53.0 53.0 53.0 53.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:62.00 stddev:1.26 (2.0%) stddev-sans-first:0.44 20:37:10 INFO - PROCESS | 2618 | Values: 66.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 63.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.39 (3.0%) stddev-sans-first:2.54 20:37:10 INFO - PROCESS | 2618 | Values: 81.0 80.0 86.0 81.0 81.0 79.0 80.0 79.0 85.0 80.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:136.00 stddev:17.32 (12.7%) stddev-sans-first:18.29 20:37:10 INFO - PROCESS | 2618 | Values: 135.0 131.0 136.0 132.0 137.0 140.0 136.0 129.0 188.0 132.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.00 stddev:2.56 (1.5%) stddev-sans-first:0.87 20:37:10 INFO - PROCESS | 2618 | Values: 173.0 166.0 166.0 164.0 165.0 165.0 166.0 166.0 166.0 164.0 20:37:10 INFO - PROCESS | 2618 | 20:37:10 INFO - PROCESS | 2618 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:2.08 (3.1%) stddev-sans-first:2.18 20:37:10 INFO - PROCESS | 2618 | Values: 66.0 67.0 72.0 67.0 66.0 66.0 67.0 66.0 68.0 64.0 20:37:10 INFO - PROCESS | 2618 | -------- Summary: end -------- 20:37:10 INFO - PROCESS | 2618 | 20:37:11 INFO - TEST-INFO | 2618: exit 0 20:37:11 INFO - TEST-OK | kraken | took 62154ms 20:37:11 INFO - SUITE-END | took 1180s 20:37:12 INFO - Completed test suite (00:19:41) 20:37:12 INFO - javascript benchmark 20:37:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3045.6649768525463, 3072.8002679825818, 3073.5697002419665, 3102.0073576455543, 3118.220444888778, 5110.770015349318, 5176.870578235546, 5200.583953283738, 5218.843434696961, 5229.163333866582, 5267.920981185276, 5288.682679039251, 5370.892582148364, 5400.459954004607, 5428.891422171554, 4735.408073990751, 4742.881427964294, 4789.760511974388, 4812.975935120319, 4842.982973575553, 4856.198727210006, 4878.829240976549, 4927.2855435961765, 4940.456549779521, 4984.87537811554, 4779.665423420362, 4795.6882802618065, 4855.344528488679, 4910.950890491085, 4914.70511769295, 4746.833860814855, 4757.976210118962, 4785.521447855222, 4838.056578967103, 4851.878703032417, 4731.455882573514, 4741.359916411275, 4742.478327383985, 4797.2564252540915, 4805.135075686375, 5205.765740541685, 5229.790808367661, 5238.711870847102, 5239.449857764956, 5260.263563101169, 5532.363778165482, 5577.6653400796085, 5593.188987596775, 5656.547476201894, 5661.433856614347, 5631.662100273997, 5688.288963879515, 5708.20085188074, 5759.740811663443, 5770.596058275923, 5286.97356513215, 5290.0, 5290.60320475962, 5297.920531192035, 5305.814296499604, 5605.859853503695, 5605.990921634145, 5750.568707346965, 5752.741126649311, 5808.0, 5514.145307477347, 5520.503154716095, 5520.613557051048, 5524.475174858382, 5526.115821468546, 5543.0, 5560.86097847549, 5569.248151499496, 5577.804776832872, 5593.496585307261, 5594.328680558359, 5597.496225339739, 5601.187827764951, 5603.271574695345, 5604.963081829875, 5587.217789509472, 5587.916181257285, 5591.440855914376, 5592.105263157875, 5602.38373778884, 4330.047389574289, 4338.82644694215, 4340.0, 4342.32693932441, 4359.215341238608, 3893.902652433712, 3914.2171565686976, 3931.233409485123, 3953.3872249801325, 3956.5252169739815, 5382.0043291991105, 5390.787072908564, 5391.946080539223, 5397.27136836522, 5397.568194544506, 5676.943230567724, 5690.77236910519, 5691.459311365414, 5700.0, 5734.713264336767, 5269.367675878842, 5301.098813201765, 5308.124159513714, 5354.277172581729, 5401.351837779492, 4814.398200224972, 4823.155947709137, 4825.227963525819, 4852.781624826821, 4865.732384718804, 5897.852553686193, 5902.380250073767, 5909.172715819789, 5915.3788852170765, 5959.910601340984, 5565.777368905198, 5613.101903695389, 5622.3815380307315, 5704.429557044263, 5707.514861236841, 5850.7074646266665, 5894.263217097863, 5897.144913987534, 5937.406259374028, 5939.851503712442, 5582.916256256078, 5585.636933599303, 5586.441355864381, 5587.078132108157, 5591.161325801162, 5579.107342825291, 5607.074832652566, 5679.204911312502, 5686.317641883001, 5693.629914055573, 5549.112142057252, 5567.331920169606, 5597.972010140086, 5627.943720562906, 5675.716214189357, 5505.9724701376235, 5547.7780888764, 5548.3619383769765, 5553.555715542666, 5598.972005139948, 5281.60387970896, 5458.0, 5516.365617953987, 5519.061759500814, 5565.5825813063375, 5419.810306639249, 5514.558835293247, 5549.084401073777, 5581.023320918742, 5661.065924122474, 5489.58828087903, 5491.670499769867, 5497.257870187634, 5500.4499550044675, 5502.559795216293], "value": 5217.2174401889015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.7475075353582, 579.3713820504753, 590.622001918772, 631.198378059864, 648.2253706820352, 32746.181345466375, 43421.0, 44087.77956110215, 44330.778346108265, 47236.52763472373, 44264.0, 44314.77842610791, 44470.777646111805, 44729.0, 44746.32880506787, 7945.920540794605, 8589.91410085899, 8740.781480463007, 8746.781330466725, 8749.431286966359, 7962.323202527792, 9907.900920990805, 10131.0, 10131.79736405275, 10408.0, 10390.428526431044, 10451.425171615558, 10457.372557646566, 10463.424511651856, 10467.110295624881, 10345.948270258637, 10381.377117372982, 10387.636432724894, 10401.84397234042, 10418.583256669725, 10128.746781330452, 10141.543630536644, 10229.130523905476, 10232.130268927076, 10236.078752912199, 41734.1653166938, 41804.79097604523, 41894.0, 41909.58090419087, 42018.789906050275, 3457.844397002141, 3545.0782796472845, 3554.893353199382, 3559.5906470755936, 3586.3544561978833, 31202.375952481052, 31351.686483135334, 31596.684033159832, 32655.6734432655, 33348.6665133348, 4035.1929614077007, 4038.1318016626274, 4127.484064491939, 4192.895177620553, 4332.0, 311468.0, 314059.0, 314462.42768786236, 314850.0, 315524.0, 59460.40539594635, 59621.70189149113, 59932.40067599355, 59945.40054599485, 60245.39754602398, 62218.0, 62393.37606623876, 62406.687966559875, 62428.68785656043, 62443.0, 14856.0, 15050.172240526657, 15054.096754194783, 15055.397784088733, 15057.623559411102, 10323.63867264642, 10596.682099537025, 10969.23215374916, 11309.773804523864, 11409.771804563863, 17958.551036223937, 18186.0, 18188.1815318311, 18199.909000455176, 18206.18072186755, 5720.742566584514, 5982.641041537521, 6052.939470605325, 6059.272887253558, 6068.8786224276155, 955.4315182466421, 985.605757696927, 1018.2922868606396, 1046.9530469530469, 1116.5757012335423, 31912.0, 32222.19444513859, 32364.35271294561, 32917.0, 33071.66928330734, 1015.735908663738, 1075.9139268858473, 1085.56577369053, 1142.2917790969652, 1157.704785279765, 16157.676846463004, 17146.742798858028, 17166.39917602878, 17166.570835729206, 17177.570560735832, 442484.0, 444454.0, 444902.0, 446636.0, 745174.0, 52376.73811630918, 55845.44154558402, 57274.42725572774, 57341.0, 62800.68599656972, 1277.7189018415934, 1310.3382791690326, 1326.104879206551, 1344.7444985452928, 1346.0443085409274, 30299.52076677387, 32624.673753262163, 32859.50710739389, 33218.33563328672, 33457.163570910925, 50935.0, 51722.48277517177, 51759.74120129375, 51762.0, 51777.0, 388560.0, 389928.0, 390406.0, 390572.0, 391731.0, 25122.12072577451, 40920.0, 41109.1778164441, 41359.793201033805, 41360.0, 42215.155696885275, 45018.77490612657, 45142.548574513836, 45154.54845451503, 45204.54795452135, 38510.42234366543, 38602.42096368501, 38924.0, 39824.800875996596, 40308.39537407, 37357.813210933775, 38578.6142138575, 38666.226675466896, 39286.60713392829, 39749.205015898944, 39234.803825980685, 40303.39544906888, 40459.59540404676, 40497.79751101226, 40537.79731101326, 31760.523592145673, 34198.31603367869, 34373.65626343704, 34813.90764083888, 35104.29791404107, 26531.336716581467, 27188.592171117052, 27320.59019114675, 27379.31551711223, 27399.726002739717], "value": 19953.345779397885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.1761741785491, 377.9225258821942, 385.545056832937, 390.47871092092055, 407.9082206503537, 13978.37131731541, 15728.60678483037, 18385.724214136797, 18719.4384168475, 18734.906325468353, 16964.236609352578, 17096.572585685364, 17178.656426871454, 17278.654426911453, 17335.0, 4201.978990105045, 5468.7539060742265, 5610.24261724666, 5636.098224284095, 5663.886722265553, 4303.591158839905, 6011.398860113975, 6188.721507532149, 6262.6868656566985, 6379.968100159493, 6101.145839582462, 6294.402031806971, 6303.527235457336, 6307.1485349477925, 6332.461740752042, 6306.180196574439, 6349.8412539686415, 6354.046892966046, 6354.364563543655, 6354.379633193506, 5386.0, 5475.4524547545325, 5494.120940649517, 5500.0, 5574.5351653215375, 22444.66333005006, 22529.774702252933, 22869.344986840177, 23024.53950920972, 23450.0, 2257.853239539441, 2441.865697386643, 2499.8125140614343, 2517.420993171581, 2666.2401215653445, 14059.22674252926, 14187.148771073666, 14217.21805300708, 14386.784198237036, 14732.410703571844, 1833.4041436533046, 1935.9612807743765, 1938.0794122791701, 1948.8635795494322, 1960.4216756056996, 71572.0, 72900.63549682217, 77219.61390193013, 77406.22593774102, 77752.61123694346, 10804.459777011276, 10880.891191087987, 11112.611058612909, 11248.0, 11410.828837567444, 15883.014623172103, 15885.126318052618, 15893.0, 15899.125548094966, 15920.52238432849, 7522.586257755766, 7708.344790692742, 7778.688852445952, 7844.019497562805, 7861.292483676382, 6907.412869906014, 6908.585484870924, 7123.180834204029, 7126.857462850714, 7141.500094993301, 4706.3411122442885, 4722.0, 4739.194336962656, 4767.6662633615315, 4779.187538118528, 1814.7187185986193, 1889.3859495663964, 1954.140178321534, 2034.104993802722, 2047.64166270902, 728.5155371677881, 868.6004437958484, 959.6305422412421, 972.8978457262027, 1007.6976906927893, 18294.817051829577, 19225.13486893065, 19326.226950922086, 19375.806241937684, 19511.0495995364, 984.3552473130111, 987.7086259553449, 987.7925635616457, 992.4690290694443, 997.870276864003, 10461.476926153662, 10803.243772935975, 10812.459377031117, 10825.188110891715, 10837.729056773644, 449097.0, 450235.0, 451668.0, 456228.0, 456600.0, 97610.0, 99073.0, 99906.5004674972, 100111.0, 104442.47778761058, 1008.5007921079113, 1035.492608621785, 1039.5374058543875, 1095.4905968724581, 1096.5997410944947, 17063.48809535716, 17344.934322790727, 17647.38234161798, 17758.46724598264, 18128.45614631563, 39995.40006899841, 40751.59248407478, 40854.79572602237, 40953.0, 42392.0, 56244.0, 56862.7156864213, 57359.0, 58640.413595863494, 58869.70565147147, 8313.12712165214, 8323.458975166595, 8537.359698022798, 8605.95697021511, 8609.741707748777, 12404.131710780159, 12729.55446559366, 12873.970082393198, 12938.676533086747, 13010.414531346301, 11120.555177792798, 11237.7752444949, 11255.324680519196, 11264.155188360743, 11752.764944700886, 11341.26281791681, 11460.770784584094, 11528.538858445903, 11532.25040372373, 11769.646910592695, 10729.892701073202, 11512.30926144434, 11574.131940104357, 11614.477348519506, 11873.465694043616, 8248.216419440143, 8317.293030092509, 8348.248657620843, 8506.702265420681, 8676.826463470821, 8303.584820759059, 10528.315659482108, 10559.260851740304, 10736.946315268686, 11383.772324553296], "value": 9155.897308149819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.4714482069228, 1447.565730280916, 1548.5044785668583, 1675.2210222246667, 1700.4473546097522, 7227.385672217868, 7439.293267139627, 7706.922930770691, 8143.33061722347, 8148.103708592058, 7677.3858091352695, 7951.562664053475, 7953.642086106124, 8101.959490202556, 8113.756587302376, 2243.4054975431523, 2285.5543169082034, 2293.289080385078, 2294.3919861236786, 2296.701428814252, 2008.6786114221727, 2066.7209926659934, 2251.324602619216, 2296.3455415206668, 2316.363000174957, 2219.7447293561204, 2272.556851413975, 2277.806386457145, 2289.290320000797, 2303.7581053989343, 2239.283429302624, 2248.921287754929, 2270.182734215681, 2272.3410211038777, 2284.097781376355, 2252.673362362448, 2264.9886750566307, 2284.588774020676, 2288.6681431192383, 2314.0975019742314, 8038.8794168087525, 8076.0, 8127.268545830844, 8128.796780080486, 8137.674493020331, 4963.776630051656, 5091.261767043795, 5091.41448733393, 5095.541401273866, 5103.157978933472, 3017.109952563999, 3095.1952492351925, 3141.780075394723, 3165.841707914618, 3176.7299779518767, 1017.5065093429681, 1024.9692509224735, 1964.7544056992876, 1978.940631781049, 1989.263972330247, 10911.454427278604, 10931.890681093246, 10999.120070394349, 11031.22781405302, 11082.722931926766, 4178.247915375262, 4291.54938731435, 4371.23503386906, 4387.868363949086, 4394.582514661102, 8862.512561809033, 8862.77843053916, 8870.467771933705, 8870.600822962982, 8873.822523549492, 4973.0, 4979.128652485873, 4986.276989836526, 4988.8752781180765, 4992.276119962585, 4509.278515437514, 4537.546245375502, 4583.702059366131, 4585.174668559691, 4599.195140850338, 3473.8610455581997, 3523.823808809549, 3531.9646803531637, 3565.8395372208315, 3577.3918433866306, 1026.296986564206, 1044.9634262800766, 1115.4144074361025, 1116.547798141754, 1130.4178348150708, 855.461059532503, 969.5588507229194, 972.9951350243346, 979.1921664626711, 1011.8785745710414, 5174.327337446183, 5217.6347655664495, 5225.477452254744, 5439.673619582759, 5880.382559831241, 921.318224513855, 925.5233554719325, 932.7668082979255, 933.5518950903604, 971.1987610221596, 4020.135670830727, 4077.6330130287824, 4083.1425400666194, 4132.214879172948, 4141.461609990682, 9710.708678739507, 9916.851247231298, 10026.749331266778, 10027.849582256273, 10051.949740251252, 9245.491497967676, 9436.90563094374, 9651.083147101013, 9652.75868103289, 9967.152792012761, 7145.535540189975, 7283.053203083565, 7503.624818759149, 7516.210797866145, 7656.846863062708, 5494.36814766307, 5593.692346920959, 5641.210230567806, 5695.174199740932, 5808.767649293981, 8830.91169088301, 8956.910430895608, 8991.0, 9113.635454581743, 9117.589708463267, 9012.654210797857, 9159.725208243763, 9160.725178244664, 9274.721758347261, 9281.767955801159, 7602.581857997863, 7715.382769378549, 7718.614069296625, 7723.073231212291, 7724.1889601593275, 7630.465867389229, 7651.61741912891, 7707.0, 7721.266479684421, 7725.304722574995, 6917.861642767217, 6961.303869612998, 7002.2647622000995, 7026.332498412643, 7162.46281528877, 6867.725290988305, 6996.475264355296, 7001.50989430735, 7030.15638123408, 7037.261087585935, 6413.0, 6580.868382632225, 6588.538802283986, 6589.04458853454, 6592.967035164794, 4977.925331119964, 5066.695998240118, 5073.264376665289, 5164.354455693038, 5197.4282828889545, 4582.839600613963, 4800.495947925418, 4854.271859221145, 5085.77114029877, 5254.763535640829], "value": 4387.354425989227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.91443310306215, 328.8421557652327, 331.14564423786635, 331.60538958639216, 334.5633947698254, 5347.6791392516425, 5352.717825739413, 5398.298221231239, 5411.215373770801, 5411.3506379234495, 5050.802900738172, 5080.0, 5185.325907632012, 5210.973945130269, 5325.094733895237, 4128.112455821998, 4129.483814523184, 4138.379243113534, 4151.23202207591, 4161.459010328653, 4162.895927601804, 4169.62473377396, 4170.332746760519, 4171.290880550314, 4174.561671024544, 3845.788766380062, 3968.5237771467464, 4113.629773320401, 4126.91746165078, 4143.0885205254945, 4095.3037983542868, 4104.8506418202805, 4110.383442483621, 4124.319487284594, 4133.256013917508, 3639.6542328478754, 3654.926901461956, 3655.2689462107683, 3667.3032123896373, 3671.375866102769, 4996.4254110777365, 5134.204198349262, 5141.948580514222, 5146.044503088802, 5150.330457040561, 4684.742339171345, 4684.922467832418, 4707.375955295418, 4720.43354797423, 4728.905421891577, 5583.832485025456, 5603.887922241533, 5612.681019960306, 5624.072028115356, 5625.6905870177125, 3761.172542040774, 3916.608339166089, 3983.063979964706, 3985.143194213229, 3990.7206495545324, 5747.339056008571, 5756.3667996519935, 5766.394528574481, 5793.420657934173, 5803.477687008147, 4043.292423825819, 4043.5147782266317, 4043.696722745806, 4067.918641627151, 4073.3075377185355, 3902.5755599206086, 4078.510578730512, 4121.9381709274385, 4129.132882094794, 4131.8967025824595, 4681.274402467623, 4688.33564084745, 4693.249080147229, 4711.905761884743, 4720.740359280273, 5568.832935011957, 5572.01896985111, 5572.665640061609, 5577.414371491016, 5613.887722245532, 1322.2397121655088, 1370.712150448397, 1373.9313034348443, 1379.1380387257964, 1389.6456403617215, 893.2496702769705, 900.8108297257518, 929.1451864284874, 930.4882314727008, 948.9430634161972, 5209.4009188943, 5215.191645294985, 5227.065985585357, 5244.868878278074, 5277.23480095384, 5500.3949565547755, 5509.531689806411, 5530.391656917735, 5545.584081193927, 5546.334439867161, 5085.338905942203, 5114.33513643224, 5169.198774190007, 5179.695035709074, 5281.894362112736, 2886.7194916016533, 2887.7112288771373, 2935.2661834541364, 2955.837428941386, 2956.339294634803, 5373.516383525502, 5744.827655170351, 5744.978515039489, 5767.682777447197, 5782.566307526952, 5461.890762184734, 5468.890622187534, 5505.944940550623, 5510.6969116698165, 5525.4474552544425, 5597.3003374578175, 5619.241402410704, 5640.210370548127, 5655.604107712421, 5706.087026075974, 5184.896302074013, 5408.432114627907, 5435.619506634498, 5465.562754979513, 5472.972635136799, 5406.243125962447, 5430.891382172414, 5435.945640543702, 5459.344878614433, 5460.34475862899, 5665.915011274917, 5683.658980461186, 5689.6956732523395, 5692.459216374438, 5695.658260504383, 3764.265968136187, 3836.501254836965, 3865.806709664449, 3892.377219644843, 3908.159745654698, 5401.648892821954, 5407.540359069523, 5448.509634132947, 5458.506555804368, 5461.290032295776, 5359.464053594609, 5361.177199752103, 5367.5169234769055, 5375.435579264121, 5380.300560927054, 5128.358955130609, 5203.791848326025, 5273.6572122811895, 5289.9471005291, 5384.896096300327, 5198.272241886215, 5224.682326722483, 5247.685138891526, 5250.684958902478, 5300.920486192787, 5498.9002199559445, 5506.972465137648, 5595.761695321904, 5638.379778224474, 5644.52021578154, 5467.261919640957, 5478.945395476696, 5498.890022199454, 5499.560035197094, 5510.834674959758], "value": 4161.2463201696255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.4401770680844, 768.8500742355242, 769.4921351907742, 799.8040480082379, 813.8715472492137, 15085.170315632637, 15898.682026359465, 15900.602484937883, 15915.761263581055, 16061.83938160618, 15534.456294029711, 15701.528954131365, 15704.37182512701, 15704.450344237954, 15708.528744137666, 655.12540758088, 677.288846710954, 755.7128291249345, 769.542122437149, 789.4197764642983, 771.4329967473902, 780.8516381887423, 782.6830133795822, 783.8275579372557, 784.4391260248921, 849.1041950741965, 850.4387104511025, 850.8978922529305, 851.0255757158051, 851.2083762101244, 691.0429055757761, 710.7583421636616, 797.7646594254651, 840.6385254340631, 842.204117109332, 596.4751019103205, 600.1837500998638, 601.3205078261536, 601.3986013986014, 606.1029676079384, 13975.09161904473, 14099.78850317246, 14108.576742697736, 14111.294435278194, 14135.081219720685, 14329.71340573193, 14368.0, 14517.0, 14556.70886582273, 14557.344919478648, 12785.552505662257, 12846.550370737074, 12934.41795119222, 13099.41052652633, 13825.585232443043, 4273.401723758645, 4292.098659281554, 4372.366006929009, 4385.890352741175, 4419.580139886705, 89771.55114224387, 90878.0, 90911.54544227303, 91139.54430227872, 91344.54327728385, 11624.0, 11643.41782910851, 11707.2975621463, 11718.648440546796, 11728.530858765724, 22415.0, 22610.66084008741, 22628.321150365515, 22738.658920116213, 22865.314040578476, 7759.689612415553, 7867.370610351272, 7978.281954623995, 8156.265936065783, 8395.118512556093, 4459.710118842265, 4509.729416235036, 4512.300593408027, 4577.679562430598, 4856.295837103671, 3288.4245257080393, 3356.2951780126446, 3359.227377703093, 3363.2768954674375, 3414.914627134292, 590.5718354193176, 596.9283685957625, 609.3845216331537, 615.9784407545715, 627.5042716254109, 542.8235823357425, 595.1994567306965, 648.419664400359, 654.7839213059679, 670.0050425118695, 9201.861972070425, 9783.168430683472, 9785.461799601091, 9796.118349348593, 9821.116099551075, 551.9305181904223, 646.4085361893908, 658.3712554510431, 674.4570620650424, 714.9428045756327, 4623.86128416148, 4910.8772280693265, 4942.184539550934, 4952.628552858478, 4969.2546118081855, 89211.0, 89283.55358223167, 89649.0, 90795.54602227079, 92958.53520732353, 73196.0, 74537.0, 75279.0, 76263.61868190624, 76908.61545692236, 67452.0, 67505.32494675089, 68515.65742171257, 69029.65485172543, 73611.89582156272, 9267.44395336282, 9493.762655933657, 9570.712878613653, 9571.18644915162, 9630.32587718853, 46372.45101959243, 47650.52349476461, 47710.522894770605, 48378.75810620924, 49093.50906491032, 63282.68358658177, 63494.36505634885, 65024.0, 65138.0, 66496.66751666211, 11735.119866010255, 11759.193723644936, 11909.52361905514, 12148.0, 12255.632331030083, 12785.16896401731, 12828.807567886302, 12951.222926624432, 13017.609471715863, 13036.934815325863, 12582.307973061203, 12842.954469342127, 12883.227006379648, 13019.414126364525, 13594.912407007614, 11699.0, 12069.283673051928, 12452.68868278264, 12634.557790477289, 12989.220646761225, 12517.24896506176, 13059.673508162372, 13232.976899521249, 13236.66908327261, 13384.263865487492, 10000.2999790014, 10390.0, 10436.739081523023, 10490.60073355268, 10606.840897386392, 9142.542872856464, 10662.626808061681, 10781.514831832743, 10935.0, 10938.343699378063], "value": 7233.425819496128, "unit": "score"}], "name": "dromaeo_css", "value": 7079.385845751579, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 113.0, 114.0, 112.0, 111.0, 112.0, 110.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 128.0, 159.0, 125.0, 124.0, 126.0, 123.0, 141.0, 125.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 163.0, 163.0, 163.0, 162.0, 161.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 80.0, 114.0, 77.0, 77.0, 77.0, 75.0, 96.0, 75.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 105.0, 82.0, 82.0, 83.0, 96.0, 83.0, 82.0, 102.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 52.0, 52.0, 61.0, 53.0, 53.0, 53.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 63.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 86.0, 81.0, 81.0, 79.0, 80.0, 79.0, 85.0, 80.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 136.0, 132.0, 137.0, 140.0, 136.0, 129.0, 188.0, 132.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 166.0, 164.0, 165.0, 165.0, 166.0, 166.0, 166.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 72.0, 67.0, 66.0, 66.0, 67.0, 66.0, 68.0, 64.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1504.8, "alertThreshold": 2.0}]} 20:37:12 INFO - Return code: 0 20:37:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:37:12 INFO - # TBPL SUCCESS # 20:37:12 INFO - Running post-action listener: _resource_record_post_action 20:37:12 INFO - [mozharness: 2016-11-30 04:37:12.220609Z] Finished run-tests step (success) 20:37:12 INFO - Running post-run listener: _resource_record_post_run 20:37:12 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393519104; Read time: 16; Write time: 344100 20:37:12 INFO - TinderboxPrint: CPU usage
13.2% 20:37:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 20:37:12 INFO - TinderboxPrint: I/O write bytes / time
393,519,104 / 344,100 20:37:12 INFO - TinderboxPrint: CPU idle
8,300.1 (86.8%) 20:37:12 INFO - TinderboxPrint: CPU user
1,206.7 (12.6%) 20:37:12 INFO - TinderboxPrint: Swap in / out
0 / 0 20:37:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105312256; Read time: 0; Write time: 98856 20:37:12 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288206848; Read time: 0; Write time: 245244 20:37:12 INFO - Running post-run listener: _upload_blobber_files 20:37:12 INFO - Blob upload gear active. 20:37:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:37:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:37:12 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:37:12 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:37:13 INFO - (blobuploader) - INFO - Open directory for files ... 20:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:14 INFO - (blobuploader) - INFO - Done attempting. 20:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:17 INFO - (blobuploader) - INFO - Done attempting. 20:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:18 INFO - (blobuploader) - INFO - Done attempting. 20:37:18 INFO - (blobuploader) - INFO - Iteration through files over. 20:37:18 INFO - Return code: 0 20:37:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:37:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:37:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585a1e3bbb266b6b86dca447074e6856f73e6d1e921e1cd116996c244c6708940a51c0899041b001c3c98c9ea1341c8943f959a92cefac69789a669f7466680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c47e96e4134a6b88b527cd60cf7bbddcc6ab0691dc2e5fde9ae1cb8b143c19a6b8ba80f80e523153848582fa898a43d90d217a118c6ee01fac4a663abdb1980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5e443bb3672cf22238a5d5b605b11fc8ad78089f3c8231d4d895f0049d33035dbd42283829b00f3de95ee1489a1ccf682bf8778957540e582ed9adb3e48a7c"} 20:37:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:37:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:37:18 INFO - Contents: 20:37:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585a1e3bbb266b6b86dca447074e6856f73e6d1e921e1cd116996c244c6708940a51c0899041b001c3c98c9ea1341c8943f959a92cefac69789a669f7466680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c47e96e4134a6b88b527cd60cf7bbddcc6ab0691dc2e5fde9ae1cb8b143c19a6b8ba80f80e523153848582fa898a43d90d217a118c6ee01fac4a663abdb1980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5e443bb3672cf22238a5d5b605b11fc8ad78089f3c8231d4d895f0049d33035dbd42283829b00f3de95ee1489a1ccf682bf8778957540e582ed9adb3e48a7c"} 20:37:18 INFO - Running post-run listener: copy_logs_to_upload_dir 20:37:18 INFO - Copying logs to upload dir... 20:37:18 INFO - mkdir: /builds/slave/test/build/upload/logs 20:37:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.583601 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-11-29 20:37:17.985692) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:37:17.989061) ========= 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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585a1e3bbb266b6b86dca447074e6856f73e6d1e921e1cd116996c244c6708940a51c0899041b001c3c98c9ea1341c8943f959a92cefac69789a669f7466680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c47e96e4134a6b88b527cd60cf7bbddcc6ab0691dc2e5fde9ae1cb8b143c19a6b8ba80f80e523153848582fa898a43d90d217a118c6ee01fac4a663abdb1980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5e443bb3672cf22238a5d5b605b11fc8ad78089f3c8231d4d895f0049d33035dbd42283829b00f3de95ee1489a1ccf682bf8778957540e582ed9adb3e48a7c"} build_url:https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011891 build_url: 'https://queue.taskcluster.net/v1/task/a96jVX9ZS6GwwpDQTVimDQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/585a1e3bbb266b6b86dca447074e6856f73e6d1e921e1cd116996c244c6708940a51c0899041b001c3c98c9ea1341c8943f959a92cefac69789a669f7466680c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c47e96e4134a6b88b527cd60cf7bbddcc6ab0691dc2e5fde9ae1cb8b143c19a6b8ba80f80e523153848582fa898a43d90d217a118c6ee01fac4a663abdb1980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c5e443bb3672cf22238a5d5b605b11fc8ad78089f3c8231d4d895f0049d33035dbd42283829b00f3de95ee1489a1ccf682bf8778957540e582ed9adb3e48a7c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:37:18.015140) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:37:18.015461) ========= 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=4300f881736b422476174874000001bc-1480479394.856700-101636399 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:37:18.097778) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 20:37:18.098089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 20:37:18.098376) ========= ========= Total master_lag: 0.18 =========