builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-073 starttime: 1487265331.34 results: success (0) buildid: 20170216073619 builduid: 01753a70e3fb4e14a92f34dc8d3f5228 revision: d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.342251) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.342999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.343528) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.361567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.362035) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-16 09:15:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.2M=0s 2017-02-16 09:15:32 (40.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.306892 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.686164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.686952) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014116 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:31.738215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 09:15:31.738866) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 --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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-16 09:15:32,235 truncating revision to first 12 chars 2017-02-16 09:15:32,236 Setting DEBUG logging. 2017-02-16 09:15:32,236 attempt 1/10 2017-02-16 09:15:32,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d0d5f1187d75?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-16 09:15:33,677 unpacking tar archive at: mozilla-beta-d0d5f1187d75/testing/mozharness/ program finished with exit code 0 elapsedTime=1.744318 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-16 09:15:33.497664) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:33.498051) ========= script_repo_revision: d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:33.498870) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:33.499195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-16 09:15:33.509438) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-02-16 09:15:33.509790) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:34 INFO - MultiFileLogger online at 20170216 09:15:34 in /builds/slave/test 09:15:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:15:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:34 INFO - {'append_to_log': False, 09:15:34 INFO - 'base_work_dir': '/builds/slave/test', 09:15:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:15:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:34 INFO - 'branch': 'Mozilla-Beta', 09:15:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:34 INFO - 'config_files': ('talos/linux_config.py',), 09:15:34 INFO - 'default_actions': ('clobber', 09:15:34 INFO - 'read-buildbot-config', 09:15:34 INFO - 'download-and-extract', 09:15:34 INFO - 'populate-webroot', 09:15:34 INFO - 'create-virtualenv', 09:15:34 INFO - 'install', 09:15:34 INFO - 'run-tests'), 09:15:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:34 INFO - 'download_minidump_stackwalk': True, 09:15:34 INFO - 'download_symbols': 'ondemand', 09:15:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:34 INFO - 'installer_path': 'installer.exe', 09:15:34 INFO - 'log_level': 'info', 09:15:34 INFO - 'log_name': 'talos', 09:15:34 INFO - 'log_to_console': True, 09:15:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:34 INFO - 'opt_config_files': (), 09:15:34 INFO - 'pip_index': False, 09:15:34 INFO - 'sps_profile': False, 09:15:34 INFO - 'sps_profile_interval': 0, 09:15:34 INFO - 'suite': 'dromaeojs', 09:15:34 INFO - 'system_bits': '32', 09:15:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:34 INFO - 'title': 'talos-linux64-ix-073', 09:15:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:34 INFO - 'use_talos_json': True, 09:15:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:34 INFO - 'work_dir': 'build'} 09:15:34 INFO - [mozharness: 2017-02-16 17:15:34.094101Z] Running clobber step. 09:15:34 INFO - Running pre-action listener: _resource_record_pre_action 09:15:34 INFO - Running main action method: clobber 09:15:34 INFO - rmtree: /builds/slave/test/build 09:15:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:35 INFO - Running post-action listener: _resource_record_post_action 09:15:35 INFO - [mozharness: 2017-02-16 17:15:35.016828Z] Finished clobber step (success) 09:15:35 INFO - [mozharness: 2017-02-16 17:15:35.016927Z] Running read-buildbot-config step. 09:15:35 INFO - Running pre-action listener: _resource_record_pre_action 09:15:35 INFO - Running main action method: read_buildbot_config 09:15:35 INFO - Using buildbot properties: 09:15:35 INFO - { 09:15:35 INFO - "project": "", 09:15:35 INFO - "product": "firefox", 09:15:35 INFO - "script_repo_revision": "production", 09:15:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:15:35 INFO - "repository": "", 09:15:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:15:35 INFO - "buildid": "20170216073619", 09:15:35 INFO - "pgo_build": "True", 09:15:35 INFO - "basedir": "/builds/slave/test", 09:15:35 INFO - "buildnumber": 144, 09:15:35 INFO - "slavename": "talos-linux64-ix-073", 09:15:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:15:35 INFO - "platform": "ubuntu64_hw", 09:15:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:15:35 INFO - "branch": "mozilla-beta", 09:15:35 INFO - "repo_path": "releases/mozilla-beta", 09:15:35 INFO - "revision": "d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842", 09:15:35 INFO - "stage_platform": "linux64-pgo", 09:15:35 INFO - "builduid": "01753a70e3fb4e14a92f34dc8d3f5228", 09:15:35 INFO - "slavebuilddir": "test" 09:15:35 INFO - } 09:15:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 09:15:35 INFO - Running post-action listener: _resource_record_post_action 09:15:35 INFO - [mozharness: 2017-02-16 17:15:35.019347Z] Finished read-buildbot-config step (success) 09:15:35 INFO - [mozharness: 2017-02-16 17:15:35.019435Z] Running download-and-extract step. 09:15:35 INFO - Running pre-action listener: _resource_record_pre_action 09:15:35 INFO - Running main action method: download_and_extract 09:15:35 INFO - mkdir: /builds/slave/test/build/tests 09:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:35 INFO - trying https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 09:15:35 INFO - Downloading https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 09:15:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:35 INFO - Downloaded 1327 bytes. 09:15:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 09:15:35 INFO - Using the following test package requirements: 09:15:35 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 09:15:35 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:35 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 09:15:35 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'jsshell-linux-x86_64.zip'], 09:15:35 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:35 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 09:15:35 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 09:15:35 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 09:15:35 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:35 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 09:15:35 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:35 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 09:15:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 09:15:36 INFO - Http code: 200 09:15:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 09:15:36 INFO - x-amz-cf-id: 1x2-37hsbC2Seu-XeAMv_0Acgp5sNfZYqWN51JmBLhw7jV4QqmcIng== 09:15:36 INFO - x-amz-version-id: bdYvoEbxlGwX6DTq2zCgKhafL6n_B99e 09:15:36 INFO - Expected file size: 21959334 09:15:36 INFO - Obtained file size: 21959334 09:15:40 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:15:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 09:15:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:40 INFO - Fetch https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 09:15:41 INFO - Http code: 200 09:15:41 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 09:15:41 INFO - x-amz-cf-id: 6bFh0aCo1qIQSwauRIyblCaCApP96EgJnEdgrOrjdv4294cl6_UV-g== 09:15:41 INFO - x-amz-version-id: bdYvoEbxlGwX6DTq2zCgKhafL6n_B99e 09:15:41 INFO - Expected file size: 21959334 09:15:41 INFO - Obtained file size: 21959334 09:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 09:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 09:15:46 INFO - Http code: 200 09:15:46 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:15:46 INFO - x-amz-cf-id: b7Odu6VeCrMN22pmBrvKMKMTPXLfsK-b9FijQFAlqfdexokI4PXxNQ== 09:15:46 INFO - x-amz-version-id: 7ke.kCSrRpNt70iqTDT8r14yGAi9Y.TF 09:15:46 INFO - Expected file size: 14403005 09:15:46 INFO - Obtained file size: 14403005 09:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:46 INFO - trying https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 09:15:46 INFO - Downloading https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 09:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:15:47 INFO - Downloaded 57745194 bytes. 09:15:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 09:15:47 INFO - mkdir: /builds/slave/test/properties 09:15:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:47 INFO - Contents: 09:15:47 INFO - build_url:https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 09:15:48 INFO - Running post-action listener: _resource_record_post_action 09:15:48 INFO - Running post-action listener: set_extra_try_arguments 09:15:48 INFO - [mozharness: 2017-02-16 17:15:48.650537Z] Finished download-and-extract step (success) 09:15:48 INFO - [mozharness: 2017-02-16 17:15:48.650762Z] Running populate-webroot step. 09:15:48 INFO - Running pre-action listener: _resource_record_pre_action 09:15:48 INFO - Running main action method: populate_webroot 09:15:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:48 INFO - '--tppagecycles', 09:15:48 INFO - '7'], 09:15:48 INFO - 'tests': ['tsvgm']}}, 09:15:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['damp', 'tps']}, 09:15:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'tests': ['damp', 'tps']}, 09:15:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['dromaeo_dom']}, 09:15:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['basic_compositor_video']}, 09:15:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['a11yr', 09:15:48 INFO - 'ts_paint', 09:15:48 INFO - 'tpaint', 09:15:48 INFO - 'sessionrestore', 09:15:48 INFO - 'sessionrestore_no_auto_restore', 09:15:48 INFO - 'tabpaint']}, 09:15:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['tsvgx', 09:15:48 INFO - 'tsvgr_opacity', 09:15:48 INFO - 'tart', 09:15:48 INFO - 'tscrollx', 09:15:48 INFO - 'cart']}, 09:15:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:48 INFO - 'tsvgr_opacity', 09:15:48 INFO - 'tart', 09:15:48 INFO - 'tscrollx', 09:15:48 INFO - 'cart']}, 09:15:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'talos_options': ['--disable-e10s'], 09:15:48 INFO - 'tests': ['tp5o']}, 09:15:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'tests': ['tp5o']}, 09:15:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'talos_options': ['--disable-e10s', 09:15:48 INFO - '--xperf_path', 09:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:48 INFO - 'tests': ['tp5n']}, 09:15:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:48 INFO - 'talos_options': ['--xperf_path', 09:15:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:48 INFO - 'tests': ['tp5n']}}, 09:15:48 INFO - 'talos.zip': {'path': '', 09:15:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:48 INFO - Running post-action listener: _resource_record_post_action 09:15:48 INFO - [mozharness: 2017-02-16 17:15:48.668550Z] Finished populate-webroot step (success) 09:15:48 INFO - [mozharness: 2017-02-16 17:15:48.668638Z] Running create-virtualenv step. 09:15:48 INFO - Running pre-action listener: _resource_record_pre_action 09:15:48 INFO - Running main action method: create_virtualenv 09:15:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:48 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 09:15:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:48 INFO - Using real prefix '/usr' 09:15:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:51 INFO - Installing pip.................done. 09:15:51 INFO - Return code: 0 09:15:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:15:51 INFO - Reading from file tmpfile_stdout 09:15:51 INFO - Output received: 09:15:51 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) 09:15:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:51 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15:51 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 09:15:51 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 09:15:51 INFO - Using env: {'DISPLAY': ':0', 09:15:51 INFO - 'HOME': '/home/cltbld', 09:15:51 INFO - 'LANG': 'en_US.UTF-8', 09:15:51 INFO - 'LANGUAGE': 'en_US:en', 09:15:51 INFO - 'LOGNAME': 'cltbld', 09:15:51 INFO - 'MAIL': '/var/mail/cltbld', 09:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:51 INFO - 'MOZ_NO_REMOTE': '1', 09:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:51 INFO - 'NO_EM_RESTART': '1', 09:15:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:51 INFO - 'PWD': '/builds/slave/test', 09:15:51 INFO - 'SHELL': '/bin/bash', 09:15:51 INFO - 'SHLVL': '1', 09:15:51 INFO - 'TERM': 'linux', 09:15:51 INFO - 'TMOUT': '86400', 09:15:51 INFO - 'USER': 'cltbld', 09:15:51 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:51 INFO - '_': '/tools/buildbot/bin/python'} 09:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:51 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 09:15:51 INFO - Cleaning up... 09:15:51 INFO - Return code: 0 09:15:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:51 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15:51 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 09:15:51 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 09:15:51 INFO - Using env: {'DISPLAY': ':0', 09:15:51 INFO - 'HOME': '/home/cltbld', 09:15:51 INFO - 'LANG': 'en_US.UTF-8', 09:15:51 INFO - 'LANGUAGE': 'en_US:en', 09:15:51 INFO - 'LOGNAME': 'cltbld', 09:15:51 INFO - 'MAIL': '/var/mail/cltbld', 09:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:51 INFO - 'MOZ_NO_REMOTE': '1', 09:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:51 INFO - 'NO_EM_RESTART': '1', 09:15:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:51 INFO - 'PWD': '/builds/slave/test', 09:15:51 INFO - 'SHELL': '/bin/bash', 09:15:51 INFO - 'SHLVL': '1', 09:15:51 INFO - 'TERM': 'linux', 09:15:51 INFO - 'TMOUT': '86400', 09:15:51 INFO - 'USER': 'cltbld', 09:15:51 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:51 INFO - '_': '/tools/buildbot/bin/python'} 09:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:51 INFO - Downloading/unpacking psutil>=3.1.1 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:54 INFO - Installing collected packages: psutil 09:15:54 INFO - Running setup.py install for psutil 09:15:54 INFO - building 'psutil._psutil_linux' extension 09:15:54 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 09:15:54 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 09:15:54 INFO - building 'psutil._psutil_posix' extension 09:15:54 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 09:15:54 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 09:15:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:54 INFO - Successfully installed psutil 09:15:54 INFO - Cleaning up... 09:15:54 INFO - Return code: 0 09:15:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:54 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15:54 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 09:15:54 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 09:15:54 INFO - Using env: {'DISPLAY': ':0', 09:15:54 INFO - 'HOME': '/home/cltbld', 09:15:54 INFO - 'LANG': 'en_US.UTF-8', 09:15:54 INFO - 'LANGUAGE': 'en_US:en', 09:15:54 INFO - 'LOGNAME': 'cltbld', 09:15:54 INFO - 'MAIL': '/var/mail/cltbld', 09:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:54 INFO - 'MOZ_NO_REMOTE': '1', 09:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:54 INFO - 'NO_EM_RESTART': '1', 09:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:54 INFO - 'PWD': '/builds/slave/test', 09:15:54 INFO - 'SHELL': '/bin/bash', 09:15:54 INFO - 'SHLVL': '1', 09:15:54 INFO - 'TERM': 'linux', 09:15:54 INFO - 'TMOUT': '86400', 09:15:54 INFO - 'USER': 'cltbld', 09:15:54 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:54 INFO - '_': '/tools/buildbot/bin/python'} 09:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:56 INFO - Installing collected packages: mozsystemmonitor 09:15:56 INFO - Running setup.py install for mozsystemmonitor 09:15:56 INFO - Successfully installed mozsystemmonitor 09:15:56 INFO - Cleaning up... 09:15:57 INFO - Return code: 0 09:15:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:57 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15:57 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 09:15:57 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 09:15:57 INFO - Using env: {'DISPLAY': ':0', 09:15:57 INFO - 'HOME': '/home/cltbld', 09:15:57 INFO - 'LANG': 'en_US.UTF-8', 09:15:57 INFO - 'LANGUAGE': 'en_US:en', 09:15:57 INFO - 'LOGNAME': 'cltbld', 09:15:57 INFO - 'MAIL': '/var/mail/cltbld', 09:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:57 INFO - 'MOZ_NO_REMOTE': '1', 09:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:57 INFO - 'NO_EM_RESTART': '1', 09:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:57 INFO - 'PWD': '/builds/slave/test', 09:15:57 INFO - 'SHELL': '/bin/bash', 09:15:57 INFO - 'SHLVL': '1', 09:15:57 INFO - 'TERM': 'linux', 09:15:57 INFO - 'TMOUT': '86400', 09:15:57 INFO - 'USER': 'cltbld', 09:15:57 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:57 INFO - '_': '/tools/buildbot/bin/python'} 09:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:57 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:57 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 09:15:57 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 09:15:57 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 09:15:57 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 09:15:57 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 09:15:57 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 09:15:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:59 INFO - warning: no files found matching '*.txt' 09:15:59 INFO - no previously-included directories found matching 'build' 09:15:59 INFO - no previously-included directories found matching 'dist' 09:15:59 INFO - no previously-included directories found matching '.git*' 09:15:59 INFO - Installing collected packages: jsonschema, functools32 09:15:59 INFO - Running setup.py install for functools32 09:15:59 INFO - warning: no files found matching '*.txt' 09:15:59 INFO - no previously-included directories found matching 'build' 09:15:59 INFO - no previously-included directories found matching 'dist' 09:15:59 INFO - no previously-included directories found matching '.git*' 09:15:59 INFO - Successfully installed jsonschema functools32 09:15:59 INFO - Cleaning up... 09:15:59 INFO - Return code: 0 09:15:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', '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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:15: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 functools32==3.2.3-2 09:15:59 INFO - Using env: {'DISPLAY': ':0', 09:15:59 INFO - 'HOME': '/home/cltbld', 09:15:59 INFO - 'LANG': 'en_US.UTF-8', 09:15:59 INFO - 'LANGUAGE': 'en_US:en', 09:15:59 INFO - 'LOGNAME': 'cltbld', 09:15:59 INFO - 'MAIL': '/var/mail/cltbld', 09:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:59 INFO - 'MOZ_NO_REMOTE': '1', 09:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:59 INFO - 'NO_EM_RESTART': '1', 09:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:59 INFO - 'PWD': '/builds/slave/test', 09:15:59 INFO - 'SHELL': '/bin/bash', 09:15:59 INFO - 'SHLVL': '1', 09:15:59 INFO - 'TERM': 'linux', 09:15:59 INFO - 'TMOUT': '86400', 09:15:59 INFO - 'USER': 'cltbld', 09:15:59 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:59 INFO - '_': '/tools/buildbot/bin/python'} 09:15:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:59 INFO - Cleaning up... 09:15:59 INFO - Return code: 0 09:15:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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', '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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:15: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:15: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 blobuploader==1.2.4 09:15:59 INFO - Using env: {'DISPLAY': ':0', 09:15:59 INFO - 'HOME': '/home/cltbld', 09:15:59 INFO - 'LANG': 'en_US.UTF-8', 09:15:59 INFO - 'LANGUAGE': 'en_US:en', 09:15:59 INFO - 'LOGNAME': 'cltbld', 09:15:59 INFO - 'MAIL': '/var/mail/cltbld', 09:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:59 INFO - 'MOZ_NO_REMOTE': '1', 09:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:59 INFO - 'NO_EM_RESTART': '1', 09:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:59 INFO - 'PWD': '/builds/slave/test', 09:15:59 INFO - 'SHELL': '/bin/bash', 09:15:59 INFO - 'SHLVL': '1', 09:15:59 INFO - 'TERM': 'linux', 09:15:59 INFO - 'TMOUT': '86400', 09:15:59 INFO - 'USER': 'cltbld', 09:15:59 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:59 INFO - '_': '/tools/buildbot/bin/python'} 09:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:00 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 09:16:00 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 09:16:00 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 09:16:00 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 09:16:00 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 09:16:00 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 09:16:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:02 INFO - Downloading docopt-0.6.1.tar.gz 09:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:02 INFO - Running setup.py install for blobuploader 09:16:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:03 INFO - Running setup.py install for requests 09:16:03 INFO - Running setup.py install for docopt 09:16:03 INFO - Successfully installed blobuploader requests docopt 09:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:03 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')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:16:03 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 09:16:03 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 09:16:03 INFO - Using env: {'DISPLAY': ':0', 09:16:03 INFO - 'HOME': '/home/cltbld', 09:16:03 INFO - 'LANG': 'en_US.UTF-8', 09:16:03 INFO - 'LANGUAGE': 'en_US:en', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MAIL': '/var/mail/cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SHLVL': '1', 09:16:03 INFO - 'TERM': 'linux', 09:16:03 INFO - 'TMOUT': '86400', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - '_': '/tools/buildbot/bin/python'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 INFO - Running setup.py (path:/tmp/pip-AUQQYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:03 INFO - Running setup.py (path:/tmp/pip-8lV0Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:03 INFO - Running setup.py (path:/tmp/pip-e8nOj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:03 INFO - Running setup.py (path:/tmp/pip-J0ws47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:04 INFO - Running setup.py (path:/tmp/pip-MYt3cD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:04 INFO - Running setup.py (path:/tmp/pip-3kI4kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:04 INFO - Running setup.py (path:/tmp/pip-PE2JFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 INFO - Running setup.py (path:/tmp/pip-2Q00wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:04 INFO - Running setup.py (path:/tmp/pip-WZcm4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:04 INFO - Running setup.py (path:/tmp/pip-V4E5LN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:04 INFO - Running setup.py (path:/tmp/pip-J0YItE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:04 INFO - Running setup.py (path:/tmp/pip-Cf3wux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:04 INFO - Running setup.py (path:/tmp/pip-ZdqOAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:04 INFO - Running setup.py (path:/tmp/pip-EXRDdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:04 INFO - Running setup.py (path:/tmp/pip-cTes5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:04 INFO - Running setup.py (path:/tmp/pip-qrd6Lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:04 INFO - Running setup.py (path:/tmp/pip-qlBxho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:04 INFO - Running setup.py install for manifestparser 09:16:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozcrash 09:16:05 INFO - Running setup.py install for mozdebug 09:16:05 INFO - Running setup.py install for mozdevice 09:16:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozfile 09:16:05 INFO - Running setup.py install for mozhttpd 09:16:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozinfo 09:16:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozInstall 09:16:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:05 INFO - Running setup.py install for mozleak 09:16:05 INFO - Running setup.py install for mozlog 09:16:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for moznetwork 09:16:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozprocess 09:16:06 INFO - Running setup.py install for mozprofile 09:16:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozrunner 09:16:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Running setup.py install for mozscreenshot 09:16:06 INFO - Running setup.py install for moztest 09:16:06 INFO - Running setup.py install for mozversion 09:16:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:06 INFO - Cleaning up... 09:16:06 INFO - Return code: 0 09:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:06 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')]} 09:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 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')]} 09:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:06 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:16:06 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 09:16:06 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 09:16:06 INFO - Using env: {'DISPLAY': ':0', 09:16:06 INFO - 'HOME': '/home/cltbld', 09:16:06 INFO - 'LANG': 'en_US.UTF-8', 09:16:06 INFO - 'LANGUAGE': 'en_US:en', 09:16:06 INFO - 'LOGNAME': 'cltbld', 09:16:06 INFO - 'MAIL': '/var/mail/cltbld', 09:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:06 INFO - 'MOZ_NO_REMOTE': '1', 09:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:06 INFO - 'NO_EM_RESTART': '1', 09:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:06 INFO - 'PWD': '/builds/slave/test', 09:16:06 INFO - 'SHELL': '/bin/bash', 09:16:06 INFO - 'SHLVL': '1', 09:16:06 INFO - 'TERM': 'linux', 09:16:06 INFO - 'TMOUT': '86400', 09:16:06 INFO - 'USER': 'cltbld', 09:16:06 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:06 INFO - '_': '/tools/buildbot/bin/python'} 09:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:07 INFO - Running setup.py (path:/tmp/pip-QVgCsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:07 INFO - Running setup.py (path:/tmp/pip-rwsDFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:07 INFO - Running setup.py (path:/tmp/pip-2aDjUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:07 INFO - Running setup.py (path:/tmp/pip-7moHis-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:07 INFO - Running setup.py (path:/tmp/pip-X5Dm86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:07 INFO - Running setup.py (path:/tmp/pip-8VjzFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:07 INFO - Running setup.py (path:/tmp/pip-bpPGc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:07 INFO - Running setup.py (path:/tmp/pip-EBuwYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:07 INFO - Running setup.py (path:/tmp/pip-xkyG2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:07 INFO - Running setup.py (path:/tmp/pip-Tet8ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:07 INFO - Running setup.py (path:/tmp/pip-htjEFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:07 INFO - Running setup.py (path:/tmp/pip-H4PSaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:07 INFO - Running setup.py (path:/tmp/pip-yUKsH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:07 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)) 09:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:07 INFO - Running setup.py (path:/tmp/pip-02vrac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:08 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)) 09:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:08 INFO - Running setup.py (path:/tmp/pip-marALV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:08 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)) 09:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:08 INFO - Running setup.py (path:/tmp/pip-rhvwF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:08 INFO - Running setup.py (path:/tmp/pip-4SZC_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:08 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 09:16:10 INFO - Downloading blessings-1.6.tar.gz 09:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:10 INFO - Installing collected packages: blessings 09:16:10 INFO - Running setup.py install for blessings 09:16:10 INFO - Successfully installed blessings 09:16:10 INFO - Cleaning up... 09:16:10 INFO - Return code: 0 09:16:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:10 INFO - Reading from file tmpfile_stdout 09:16:10 INFO - Current package versions: 09:16:10 INFO - argparse == 1.2.1 09:16:10 INFO - blessings == 1.6 09:16:10 INFO - blobuploader == 1.2.4 09:16:10 INFO - docopt == 0.6.1 09:16:10 INFO - functools32 == 3.2.3-2 09:16:10 INFO - jsonschema == 2.5.1 09:16:10 INFO - manifestparser == 1.1 09:16:10 INFO - mozInstall == 1.12 09:16:10 INFO - mozcrash == 1.0 09:16:10 INFO - mozdebug == 0.1 09:16:10 INFO - mozdevice == 0.48 09:16:10 INFO - mozfile == 1.2 09:16:10 INFO - mozhttpd == 0.7 09:16:10 INFO - mozinfo == 0.9 09:16:10 INFO - mozleak == 0.1 09:16:10 INFO - mozlog == 3.4 09:16:10 INFO - moznetwork == 0.27 09:16:10 INFO - mozprocess == 0.23 09:16:10 INFO - mozprofile == 0.28 09:16:10 INFO - mozrunner == 6.13 09:16:10 INFO - mozscreenshot == 0.1 09:16:10 INFO - mozsystemmonitor == 0.3 09:16:10 INFO - moztest == 0.8 09:16:10 INFO - mozversion == 1.4 09:16:10 INFO - psutil == 3.1.1 09:16:10 INFO - requests == 1.2.3 09:16:10 INFO - wsgiref == 0.1.2 09:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:10 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 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:16:10 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 09:16:10 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 09:16:10 INFO - Using env: {'DISPLAY': ':0', 09:16:10 INFO - 'HOME': '/home/cltbld', 09:16:10 INFO - 'LANG': 'en_US.UTF-8', 09:16:10 INFO - 'LANGUAGE': 'en_US:en', 09:16:10 INFO - 'LOGNAME': 'cltbld', 09:16:10 INFO - 'MAIL': '/var/mail/cltbld', 09:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:10 INFO - 'MOZ_NO_REMOTE': '1', 09:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:10 INFO - 'NO_EM_RESTART': '1', 09:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:10 INFO - 'PWD': '/builds/slave/test', 09:16:10 INFO - 'SHELL': '/bin/bash', 09:16:10 INFO - 'SHLVL': '1', 09:16:10 INFO - 'TERM': 'linux', 09:16:10 INFO - 'TMOUT': '86400', 09:16:10 INFO - 'USER': 'cltbld', 09:16:10 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:10 INFO - '_': '/tools/buildbot/bin/python'} 09:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 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)) 09:16:10 INFO - Cleaning up... 09:16:10 INFO - Return code: 0 09:16:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9544600f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2366ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, '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': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', '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 09:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:10 INFO - Using env: {'DISPLAY': ':0', 09:16:10 INFO - 'HOME': '/home/cltbld', 09:16:10 INFO - 'LANG': 'en_US.UTF-8', 09:16:10 INFO - 'LANGUAGE': 'en_US:en', 09:16:10 INFO - 'LOGNAME': 'cltbld', 09:16:10 INFO - 'MAIL': '/var/mail/cltbld', 09:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:10 INFO - 'MOZ_NO_REMOTE': '1', 09:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:10 INFO - 'NO_EM_RESTART': '1', 09:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:10 INFO - 'PWD': '/builds/slave/test', 09:16:10 INFO - 'SHELL': '/bin/bash', 09:16:10 INFO - 'SHLVL': '1', 09:16:10 INFO - 'TERM': 'linux', 09:16:10 INFO - 'TMOUT': '86400', 09:16:10 INFO - 'USER': 'cltbld', 09:16:10 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:10 INFO - '_': '/tools/buildbot/bin/python'} 09:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:11 INFO - Cleaning up... 09:16:11 INFO - Return code: 0 09:16:11 INFO - Running post-action listener: _resource_record_post_action 09:16:11 INFO - Running post-action listener: _start_resource_monitoring 09:16:11 INFO - Starting resource monitoring. 09:16:11 INFO - [mozharness: 2017-02-16 17:16:11.220731Z] Finished create-virtualenv step (success) 09:16:11 INFO - [mozharness: 2017-02-16 17:16:11.221228Z] Running install step. 09:16:11 INFO - Running pre-action listener: _resource_record_pre_action 09:16:11 INFO - Running main action method: install 09:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:11 INFO - Reading from file tmpfile_stdout 09:16:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:11 INFO - Reading from file tmpfile_stdout 09:16:11 INFO - Output received: 09:16:11 INFO - Usage: mozinstall [options] installer 09:16:11 INFO - Options: 09:16:11 INFO - -h, --help show this help message and exit 09:16:11 INFO - -d DEST, --destination=DEST 09:16:11 INFO - Directory to install application into. [default: 09:16:11 INFO - "/builds/slave/test"] 09:16:11 INFO - --app=APP Application being installed. [default: firefox] 09:16:11 INFO - mkdir: /builds/slave/test/build/application 09:16:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:16:25 INFO - Reading from file tmpfile_stdout 09:16:25 INFO - Output received: 09:16:25 INFO - /builds/slave/test/build/application/firefox/firefox 09:16:25 INFO - Running post-action listener: _resource_record_post_action 09:16:25 INFO - [mozharness: 2017-02-16 17:16:25.159562Z] Finished install step (success) 09:16:25 INFO - [mozharness: 2017-02-16 17:16:25.159804Z] Running run-tests step. 09:16:25 INFO - Running pre-action listener: _resource_record_pre_action 09:16:25 INFO - Running main action method: run_tests 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:25 INFO - Python 2.7.3 09:16:25 INFO - Return code: 0 09:16:25 INFO - grabbing minidump binary from tooltool 09:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:25 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 0x2363f60>, '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 0x2364d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23651d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:25 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 09:16:25 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 09:16:25 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 09:16:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:25 INFO - Return code: 0 09:16:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:16:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:16:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 09:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-073 --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 09:16:25 INFO - Using env: {'DISPLAY': ':0', 09:16:25 INFO - 'HOME': '/home/cltbld', 09:16:25 INFO - 'LANG': 'en_US.UTF-8', 09:16:25 INFO - 'LANGUAGE': 'en_US:en', 09:16:25 INFO - 'LOGNAME': 'cltbld', 09:16:25 INFO - 'MAIL': '/var/mail/cltbld', 09:16:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:25 INFO - 'MOZ_NO_REMOTE': '1', 09:16:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:16:25 INFO - 'NO_EM_RESTART': '1', 09:16:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:25 INFO - 'PWD': '/builds/slave/test', 09:16:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:25 INFO - 'SHELL': '/bin/bash', 09:16:25 INFO - 'SHLVL': '1', 09:16:25 INFO - 'TERM': 'linux', 09:16:25 INFO - 'TMOUT': '86400', 09:16:25 INFO - 'USER': 'cltbld', 09:16:25 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577', 09:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:25 INFO - '_': '/tools/buildbot/bin/python'} 09:16:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 09:16:25 INFO - mozversion application_buildid: 20170216073619 09:16:25 INFO - mozversion application_changeset: d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 09:16:25 INFO - mozversion application_display_name: Firefox 09:16:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:25 INFO - mozversion application_name: Firefox 09:16:25 INFO - mozversion application_remotingname: firefox 09:16:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:16:25 INFO - mozversion application_vendor: Mozilla 09:16:25 INFO - mozversion application_version: 52.0 09:16:25 INFO - mozversion platform_buildid: 20170216073619 09:16:25 INFO - mozversion platform_changeset: d0d5f1187d755b0d1fc65baaecd0ca2e5d16d842 09:16:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:16:25 INFO - mozversion platform_version: 52.0 09:16:25 INFO - using testdate: 1487265385 09:16:25 INFO - actual date: 1487265385 09:16:25 INFO - starting webserver on 'localhost:52706' 09:16:25 INFO - SUITE-START | Running 2 tests 09:16:25 INFO - TEST-START | dromaeo_css 09:16:25 INFO - operating with platform_type : linux_ 09:16:25 INFO - Initialising browser for dromaeo_css test... 09:16:25 INFO - TEST-INFO | started process 5324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzQmvQc/profile http://localhost:52706/getInfo.html) 09:16:29 INFO - PROCESS | 5324 | __metrics Screen width/height:1600/1200 09:16:29 INFO - PROCESS | 5324 | colorDepth:24 09:16:29 INFO - PROCESS | 5324 | Browser inner width/height: 1024/697 09:16:29 INFO - PROCESS | 5324 | __metrics 09:16:31 INFO - PROCESS | 5324 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:31 INFO - PROCESS | 5324 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:32 INFO - TEST-INFO | 5324: exit 0 09:16:32 INFO - Browser initialized. 09:16:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:32 INFO - TEST-INFO | started process 5417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzQmvQc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:43 INFO - PROCESS | 5417 | RSS: Main: 141606912 09:16:43 INFO - PROCESS | 5417 | 09:19:45 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52706/tests/dromaeo/cssquery-ext.html) 09:19:46 INFO - PROCESS | 5417 | RSS: Main: 236232704 09:19:46 INFO - PROCESS | 5417 | 09:22:48 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-ext.html (next: http://localhost:52706/tests/dromaeo/cssquery-jquery.html) 09:22:49 INFO - PROCESS | 5417 | RSS: Main: 233775104 09:22:49 INFO - PROCESS | 5417 | 09:25:51 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52706/tests/dromaeo/cssquery-mootools.html) 09:25:52 INFO - PROCESS | 5417 | RSS: Main: 173326336 09:25:52 INFO - PROCESS | 5417 | 09:28:53 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52706/tests/dromaeo/cssquery-prototype.html) 09:28:54 INFO - PROCESS | 5417 | RSS: Main: 220934144 09:28:54 INFO - PROCESS | 5417 | 09:31:57 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52706/tests/dromaeo/cssquery-yui.html) 09:31:57 INFO - PROCESS | 5417 | RSS: Main: 171565056 09:31:57 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | Cycle 1(1): loaded http://localhost:52706/tests/dromaeo/cssquery-yui.html (next: http://localhost:52706/tests/dromaeo/cssquery-dojo.html) 09:35:00 INFO - PROCESS | 5417 | RSS: Main: 266567680 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | __start_tp_report 09:35:00 INFO - PROCESS | 5417 | _x_x_mozilla_page_load 09:35:00 INFO - PROCESS | 5417 | _x_x_mozilla_page_load_details 09:35:00 INFO - PROCESS | 5417 | |i|pagename|runs| 09:35:00 INFO - PROCESS | 5417 | |0;dojo.html;3203.4554125798613;3221.3879362921057;3235.3529294141176;3255.6581558936296;3264.249222678784;5523.806666766664;5655.066913959192;5661.094224924013;5667.8583035424135;5702.828915132547;5544.944550554494;5562.110062390018;5579.497845193931;5595.916061259084;5624.437556244374;5009.849704508862;5010.273510340999;5052.343195384595;5094.923576146342;5135.871603209912;5016.47327030662;5095.79616815327;5148.2020286855595;5153.871153221162;5155.871103222412;4994.200927851544;5015.774290156934;5027.396712394499;5049.939512702337;5052.671576347544;5022.573081288095;5117.053345131163;5123.923141152848;5132.563732082777;5156.381234251876;4968.378952630921;5047;5069.214271787879;5087.720175390389;5089.643724939256;5435.211894275348;5486.643368181056;5502.532284755801;5564.054110801174;5571.220029195916;5792.507636850873;5802.88394232113;5804.59367844251;5805.564582656318;5820.82537523875;5952.821415357503;5969.492593129589;5970.283565972068;5973.6415815050805;5976.402359764033;5453.836384908459;5483.122700367922;5483.890322193533;5534.252875861787;5557.360903496109;6095.634261944208;6117.143599896018;6117.143599896018;6125.418085281891;6136.938630613726;5861.531077513789;5867.853303667357;5869.765209391577;5874;5880.0591905295805;5759.539236861041;5803.88392232153;5866.032104702762;5906.793262235801;5926.2295901532525;5803.129530570448;5821.53427725781;5838.211841401357;5839.124131380242;5845.766169353264;5778.88442231153;5822.883542329129;5829.766809327664;5831.533477321804;5834.883302333929;4543.68194226401;4549.499555048942;4573.6569757268335;4620.838270660511;4634.536546345338;4135.875923722293;4148.0666849959125;4148.79256037203;4176.937345939813;4193.706440549193;5779.422057794271;5790.768369265266;5802.303723553201;5802.82591522255;5823.621464604872;5885.646861188342;5956.2852457704485;6118.388161183935;6122.265328160649;6125.173101631222;5568.805091821767;5583.888322233533;5594.524465420485;5658.688772117573;5674.148877668383;5085.3134826797905;5091.490850914879;5093.1087059764395;5099.923501147486;5109.489051094935;6087.543434242538;6137.232845894263;6144.188132131007;6150.569460137747;6180.103884936659;5900.468957793648;5969.462748352669;5986.970065149821;6019.187409699634;6128.571000029955;6248.843778905563;6337.176167098248;6340.556161068681;6341.714622841891;6343.809685709436;5984.611000284967;5987.191729116687;5993.460588546877;5994.640321580545;5996.910046349395;5862.94137058624;5878.588498805043;6017.849553761191;6067.120267561268;6068.666223357758;5909.85225369352;5967.49276311501;5972.163897054329;5992.131140984446;6006.459418652167;5985.072313791369;5985.221921150222;5987.43119403656;5998.460138587374;6006.519478441802;5536;5644.125160600113;5726.627769194989;5756.856078598069;5931.970340148272;5918.526517878473;5919.763209471573;5924.170616113834;5947.762089516371;5948.732307046107;5663.065594176915;5758.337791153904;5773;5776.624519406225;5779.826605201851 09:35:00 INFO - PROCESS | 5417 | |1;ext.html;568.6417556939127;598.6856900127433;618.4959258204565;637.9489640828734;653.8038588423472;32253.193670158256;42462.15075698488;43488;43594.73039522033;46478.76760616201;44078.779606102005;44320.55679443204;44504.10991780162;44600.776996115055;44699;8157.75526734199;8368.163183681643;8906.376553641248;9082.59128339223;9084.182423581877;10562.155027597773;10927.890721092766;10938.289011214285;11190.048845848112;11211.327320360764;10863.945680271587;10935;10942.17933654975;10944.781104377907;10951.45242737864;10891.83662245067;10932.289401188897;10933.125349971982;10939.671809845717;10942.726431839228;10865.51105200268;10915.727106822314;10919;10927.235093543455;10930.289531180528;42659.786701066296;42937.141257174684;42945;42967.35548966768;43086;3679.337719210541;3739.158689294914;3874.380099184117;3933.409988501719;4325.329573916048;31261.687383126104;33161.50257746136;33427.49858752121;33496.16259593505;34231.82884085589;4326.156399502099;4404.427424434835;4432.335149727534;4481.148581769454;4601.677882548223;321782;328321;328946;329762.9999999976;331365;65628.34371656318;66843;66860.33139668638;66894;66936;70829;71212.64393678102;71271;71297.64351178211;71325.64337178385;18322.083895805157;18612.44162675122;18621.906890465732;18628.534286642673;18640.906795466206;13058.08593398472;13470.797938030937;13901.58295251144;14106.224157671426;14239.074460160056;20788.376348709564;20795.376138715863;20899.79100209009;20910.790892091187;20991.055402506925;7462.776116716507;7797.649105790253;7834.8433031339055;7932.524048557105;8014.358851291882;960.6109525642127;1020.1838529176629;1032.0711359776262;1035.7876635289747;1108.578740078765;30926.53610195849;31031.44897928771;31177.532337014964;31205.68794312073;31593.210169745482;1118.4799068433056;1139.6922830835629;1140.401289323112;1158.2760774515928;1166.102101381931;16298.837011629968;16462.341506339853;16498.67002659964;16541.917290413472;16543;484785;491166;491866;494484;496465;58563.70718146382;59333;59369;59374;59426.40573594295;1281.8461784585909;1341.8792308692068;1344.1196016609138;1404.3189053309136;1404.985950140506;29691.239827668705;33396.66603334033;33688.66311336855;34673.30653386968;34980.65019349876;56434;57512.71243643755;60684;60798;60867.391326086174;442651;447274;447683;448041;448691;39334;39929.80035099806;40507.59492405038;43375.78312108419;45376.77311613421;48368.5163148364;48376.7581162106;48394.51605484041;48754.75622621864;49249.2612610804;40664.59335406608;41209.58790412058;41660.79169604133;42192.578074218865;43457;41473;41970.16059678728;42047;42229.15541689211;43311;42577;43435.13129737451;43987;44232.11535769202;46583.50623703168;35936;37502.62497374992;38211.23577528489;38237;38862.2227555453;28768.136955891358;29551;29588.704112958596;29591.85204074052;29987 09:35:00 INFO - PROCESS | 5417 | |2;jquery.html;404.61965752192935;409.42271397329773;409.89342770879574;418.62323908482364;431.1033051253393;13828;18142.367186953186;19205.51986200342;19485.512862178453;19507.70738438923;15491.457798977039;16597.336106555755;18907.149178286974;19214.231430742755;19516;5817.447342502455;6000.189974353472;6003.789867354643;6006.579539432241;6024.09638554216;5709.371969083419;5828.388019257981;5830.708464576775;5930.021546444831;5932.940670593303;5826.242588463494;5828.038373668361;5829.650220986754;5834.854128646775;5836.649801011931;5701.828945131652;5823.67969761663;5825.970870145643;5902.911456328158;5950.166976623276;5333.253344531769;5334.866628334284;5335.866603334947;5342.786288548454;5344.278522399465;23069.88465057681;23146.074157033698;23299.76700232993;23782.7621723784;23953.041878324846;2462.175171317611;2521.3948652323493;2540.593505039185;3097.725139307741;3255.576775019232;9975.351602145838;10428.843567346496;10462.47687615616;10615.363078215332;14367.78448323276;1835.4677143628464;1886.509507528039;1900.220909427142;1952.492351988479;2056.5269987902866;81780.59109704473;83598.58200708957;84262.57868710678;84821;85419;10792.730181745363;10983.505742241618;11137.109031277478;11143.275687080313;11241.494132764044;15516.37934482608;15547.30037148331;15550.300236489385;15554.45559405438;15577.221138943007;7513.511621744569;7891.9221503613635;8019.117897031322;8579.871301930476;8729.345299102466;8236.217559331853;8321.375896807642;8332.16678332174;8522.190391912758;8554.871676924851;4633.814647414133;4706.317583950308;4749.62003039756;4831.95168048322;4874.122657921537;1740.1647209339335;1836.4513191095207;1850.0009994603033;2024.7570291565107;2033.3594917600908;939.2720641502754;946.4557879219476;1050.7005503431485;1052.084686322904;1108.1854836695022;19420.417387478115;19609.509762256057;20024.49938751543;20032.59934801296;20357.491062723253;1001.9599216031423;1014.4167103915131;1025.1388833379847;1026.8870424253325;1053.346924906563;10661.89338106609;10717.410542420086;11480.138989575815;11933.284002959677;11978.820317695241;331728;332008;334160;424513;424900;83668.58165709254;88652.5567372159;92634;93082;94152.52923735474;1034.343192073039;1074.4842475611595;1074.8874914463493;1075.7525769073156;1082.1451053667672;17596.560085997953;17857.73213401774;18231;18379.90810045941;18540.629187415907;35130.82434587811;41277.38083928804;42105.57894421139;42886.78556607197;42914.57085429106;57889.71055144697;58812.70593647005;59710;60190.39809602023;60210.698946504985;8528.957355213432;8588.315027807153;8642.611082501428;8647.913520864711;8877.689280875138;10316.123129533773;11010.39442830652;13079.673008174872;13464.596062118153;13624;11118.332900026024;11275.830862537232;11870.940645296718;11876.703082423008;11938.582149625068;11722.531098755955;11776.411179441166;11796.823047654463;11825;11884.821727674265;11829.763404731684;11875.16873818859;12008.939955300517;12072.3360215189;12671.556495522613;7535.246475352421;7999.160088190656;8462.407631465738;8620.13798620133;8764.21122099014;11592.304461732323;11731.41342932867;11848.111391645833;11875.881241187823;11980.101492387932 09:35:00 INFO - PROCESS | 5417 | |3;mootools.html;1490.9925450372746;1529.8776097912164;1669.7996240451148;1797.8022417534078;1835.6328734253145;8385.161483851613;8963.058878817712;8964.103589641032;8979.102089791026;8986.640534378634;7888.368930485562;8218.054923683778;8568.443051201682;8798.472091674505;8839.337049721264;2484.3664865459327;2505.6366826810104;2509.77412032917;2514.9119780807673;2516.471540976397;2456.361346050031;2537.6701028866223;2540.5808041673104;2546.707128680207;2547.5159719653298;2410.9758902411013;2428.805695544352;2517.8363406378617;2524.1417917907906;2525.873706314686;2393.5093305872256;2396.6764486794323;2420.3707036170545;2426.6845310109748;2427.8543287402813;2385.2963375804006;2388.092524840567;2407.3981504623844;2407.807375409963;2435.2450740270397;9058.954705226497;9108.8633670495;9111.088891110901;9115.22520585751;9116.908830911672;5401.4868587484125;5543;5548.639338442988;5550.86122846928;5551.639143455663;3339.9499007514905;3379.4086034944035;3460.2214501737158;3560.928781424383;3588.5334906461994;1031.2059714020238;1044.3577200021932;1050.5535147562255;1052.726291164303;1099.8790133085354;12640.810387844189;12697.428615712315;12706.983441324764;12711.110222284444;12711.872881271162;4769.165396055676;4848.490908454632;4942.505749425029;4966.180580204225;5022.321986531844;10696.144308455305;10743.892561074446;10747.677569672922;10754.569817207224;10766;5582.469665381782;5583.413741557078;5583.804566840141;5585.832425027255;5588.385277619459;4511.142882852248;4776.928346074812;4965.4041515018425;5085.1355269604255;5135.178371460623;3982.761034337949;4089.9386509202386;4139.875803725833;4193.811278492475;4223.324268117087;1294.184663661887;1320.2078752748275;1352.8241328627216;1354.7832346824462;1363.8908887288992;942;1001.6495419043156;1003.7440452684518;1011.2617789013914;1021.1473419694489;5879.617824841457;6013.54898382623;6060.393960603904;6083.148359229712;6091.939080609226;468.23911144390365;469.8214678422246;474.54206690543793;478.74626447982627;485.4902352529829;3909.980450097788;3916.2167566486814;3940.930698768379;3942.9802850986134;3954.0905591714068;10732.356058636351;11146.442677866075;11149.832752508719;11158.888411115948;11175.94412027951;10990.285631433931;11026.44867756609;11034.889651103385;11039.889601104047;11090.390028548507;8321.417500774887;8454.61954212062;8540.359473039547;8540.44487108336;8557.828843423096;5901.5868889177345;5907.734151963086;5950.732217050157;6010.0083486224285;6034.155218269358;9785;10052.64815731446;10150.086492215736;10165.288429809842;10255.179585633281;10049.095581397709;10133.18934485254;10315.896841031494;10318.48407579633;10319.896801031893;7375.188729239887;8034.236747509211;8394.496330220207;8482.915170848213;8653.134686531297;8586.270167035871;8723.302135829246;8727.127287271222;8728.738137855875;8731.170538798804;7763.340116090196;7866.6066696666085;7917.604119793872;7949.085855126735;8080.9191908080165;7620.314171724572;7653;7760.146383897654;7762.301392874669;7939.880901786362;7173.5337203081635;7189;7256.1655409626455;7441.851162976602;7457.216992215739;5127;5232.162853943503;5258.7107709074835;5301.390340110936;5318.760655770422;5299.887023724907;5354.973225133849;5409.404965453718;5445.1832225166545;5473.698946557977 09:35:00 INFO - PROCESS | 5417 | |4;prototype.html;339.25364198762713;347.6210930086206;355.8861164427383;356.8269389346167;359.74458134724347;5270;5296.179092240704;5307.840764777063;5320.856015956569;5373.328333958255;4952.479989601094;5060.291559181717;5150.7424628768595;5155.097857874866;5264.8937127095005;4141.585841415849;4163.16820598937;4163.791810409482;4165.479315085614;4166.854160104389;4171.812268447927;4173.603507666767;4175.227582897159;4181.08016236429;4193.580641935797;4071.5724848890886;4114.189756507142;4119;4149.917001659965;4162.354835000579;4102.343625020012;4124.566920473337;4137.820721094474;4141.233871733723;4143.129942712034;3686.078480379905;3686.318031164243;3687.9262414751556;3690.944635830465;3695.7228207884245;5000.549950504472;5074.919283093951;5109.897802043938;5121.743912804345;5122.231665250205;4683.437987441494;4707.646926480494;4722.5442769759875;4724.905501889977;4724.929126063112;5422.593305502103;5425.674459532401;5437.8368648940595;5442.6462279951675;5443.346798384219;3890.8832735017995;4058.9188216235807;4059.1733719826198;4060.553339132693;4068.5182038956214;5605.2713147291;5608.2428872102155;5622.662640241599;5635.154726790973;5646.237757902672;4159.168166366739;4164.104717485755;4177.415161877339;4178.185253875526;4183.602557757008;4200.033992181754;4218.8312467500955;4226.2392769301205;4226.5139508956245;4229.281022226229;4566.26939689648;4689.601383882408;4695.81216751333;4710.411198600175;4716.679329787665;5433.4566543345345;5440.591955603346;5441.918371224435;5454.290942177491;5482.259894914216;1363.1412210307642;1413.1379858286437;1421.6090575091725;1426.543506078045;1429.7640889253157;966.367029595616;973.6786860336072;985.9802803944025;1004.7490873862089;1019.3425240719695;5084.694918304914;5086.669366491166;5112.616553758408;5119.667221630582;5130.7178105204575;5418.647787893774;5420.701861397582;5430.8819560371485;5434.8913021739345;5454.836354909359;4899.975500122477;5092.134337162691;5094.923576146361;5132.230165475208;5151.175811870083;2876.4678534470772;2884.206843118159;2888.508953477914;2889.4510043091746;2896.9420611587652;5331.946680533145;5583.553315734732;5613.887722245532;5639.0244877361365;5657.4908258256955;5382.838514844561;5474.467149198231;5478.260434841245;5488.643238189585;5496.367917689517;5589.636673616202;5613.663180209201;5615.634983725963;5615.859603509945;5620.381758006616;5260.237820330232;5367.329083864517;5409.323834520685;5432.674039557639;5470.753816078206;5377.784888604412;5387.784488620411;5388.973055134699;5393.460653934575;5396.892062158657;5519.724013799374;5597.944020559742;5621.775128994795;5667.801626943202;5680.517156041783;3900.219956008866;4074.633283004544;4119.876403707894;4137.627613514798;4142.689298302579;5634.478690753392;5641.8589535261945;5662.8018019370775;5679.659220446786;5693.857653558694;5326.280952071421;5522.978249023943;5541.284770462364;5546.500814926676;5546.833594992157;5483.314585676791;5490.945090549043;5495.2856128703015;5500.614956952975;5505.724713764215;5258.791307650719;5358.4105748366865;5406.756695948613;5440.483154100354;5485.204645326326;5610.410906854721;5611.018071837494;5615.326160860723;5621.353544342453;5622.550195984393;5449.972750136224;5452.945470545244;5476.17857321405;5487.506124448659;5497.39528651856 09:35:00 INFO - PROCESS | 5417 | |5;yui.html;739.6745432009916;761.7067429039818;790.5533373643894;809.2676128104068;884.3544212724711;14489.420423183074;15213.467528636485;15389.615259618515;15750;16201.10893900835;14123.293835308245;14211.573652790406;14487.058341207814;14490.130592164478;14494.202818844959;827.1563005734163;849.1254008371357;850.2560259772699;850.4727069217095;850.6427300533791;711.7935798618421;735.312482828557;848.2365870716343;882.9337799665019;887.8179973105498;916.6241840845255;918.9770255743626;919.5769945824928;920.1718453391934;921.7280902133853;921.2860033474111;922.2714055895834;922.7370199493113;923.4413180026088;924.1036194890945;671.8924972004456;677.0960767375559;677.3531277629901;677.8542613338155;677.864427114579;15071.54785356441;15327.463538776201;15369.231538423033;15375.384984600603;15380.538583842503;14367.281635918162;14682.265886705622;15032.774508382383;15343.232838358037;15590.376384944702;11636.534538618445;11790.646280611596;11791.823122653168;11935.462904169246;11971.880281197164;3646.2707458508403;3659.0852286928266;3723.106454450912;3763.1156678180605;3883.165119499321;54873.725631371584;61183.69408152975;61206.38793612051;61740.99999999955;62301;11279.266847654877;11912;12034.338111403782;12200.877991220152;12253.448594813255;21675.783242167377;21691.566168676854;21926.342209733735;22224.88887555552;22331;8246.134155913664;8260.793480163044;8271.439060572398;8298.336133109337;8739.60671769772;4257.723247988871;4293;4375.234333991539;4486;4549.977250113794;3524.2951409718157;3592.3533763922746;3594.6045934947133;3625.3474374612824;3657.8902632921054;605.5821483176594;606.9878602427926;613.2181468627447;623.5198896849491;632.165541485235;743.3941337809775;752.5146280648951;753.6344872736719;763.6907052643688;785.6935795039939;8727.909011373578;8779.736607901645;9255.444673319487;9675.467849268358;9815.607375705034;685.2873012067394;725.9782206533813;731.6780616528711;738.346563291491;750.1036261667256;3995.380715989026;4323.935140972888;4389.978050109729;4397.1865204937185;4492.932606010912;84506;85955;88153.55923220344;89002;92097;75473;75622.6218868902;79251.20748792554;79656;80116;42004.99999999939;48988.755056224494;58385;58768.41231587715;60285.39714602885;9493.905060949302;9596.472194029395;9793.706188814347;9825.5087245636;9834;42714;43563.34654980241;43694.344584830615;43886.78056609697;45497;41645;57309.42690573041;58161.12758308544;59830.70084649549;61031.69484152551;12837.101402901708;12949.54676586315;12963.675908102374;12987.41556629935;13594.524191653245;13544.661383465493;14129.293535323399;14369.281535922953;14665.266736663338;14862.702745945237;12738.172018818746;12817.615471535868;13052.934735326262;13581.660458488617;13584.524541640994;12885.935570322088;13583;13799.862001379857;13801.102928309629;14159.716805664035;12682.682932926382;12696.936515317364;12710.046746493865;12845;12968.610941671765;8873.112688731333;9410.905890941003;9692.515374231401;10369.274150809371;11013.779724405307;10089.344192627455;10965;11012.669619911416;11344.489497972447;11358.659240222807 09:35:00 INFO - PROCESS | 5417 | __end_tp_report 09:35:00 INFO - PROCESS | 5417 | __start_cc_report 09:35:00 INFO - PROCESS | 5417 | _x_x_mozilla_cycle_collect,1005 09:35:00 INFO - PROCESS | 5417 | __end_cc_report 09:35:00 INFO - PROCESS | 5417 | __startTimestamp1487266500920__endTimestamp 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | ------- Summary: start ------- 09:35:00 INFO - PROCESS | 5417 | Number of tests: 6 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#0] dojo.html Cycles:165 Average:5551.49 Median:5790.77 stddev:629.16 (10.9%) stddev-sans-first:603.53 09:35:00 INFO - PROCESS | 5417 | Values: 3203.5 3221.4 3235.4 3255.7 3264.2 5523.8 5655.1 5661.1 5667.9 5702.8 5544.9 5562.1 5579.5 5595.9 5624.4 5009.8 5010.3 5052.3 5094.9 5135.9 5016.5 5095.8 5148.2 5153.9 5155.9 4994.2 5015.8 5027.4 5049.9 5052.7 5022.6 5117.1 5123.9 5132.6 5156.4 4968.4 5047.0 5069.2 5087.7 5089.6 5435.2 5486.6 5502.5 5564.1 5571.2 5792.5 5802.9 5804.6 5805.6 5820.8 5952.8 5969.5 5970.3 5973.6 5976.4 5453.8 5483.1 5483.9 5534.3 5557.4 6095.6 6117.1 6117.1 6125.4 6136.9 5861.5 5867.9 5869.8 5874.0 5880.1 5759.5 5803.9 5866.0 5906.8 5926.2 5803.1 5821.5 5838.2 5839.1 5845.8 5778.9 5822.9 5829.8 5831.5 5834.9 4543.7 4549.5 4573.7 4620.8 4634.5 4135.9 4148.1 4148.8 4176.9 4193.7 5779.4 5790.8 5802.3 5802.8 5823.6 5885.6 5956.3 6118.4 6122.3 6125.2 5568.8 5583.9 5594.5 5658.7 5674.1 5085.3 5091.5 5093.1 5099.9 5109.5 6087.5 6137.2 6144.2 6150.6 6180.1 5900.5 5969.5 5987.0 6019.2 6128.6 6248.8 6337.2 6340.6 6341.7 6343.8 5984.6 5987.2 5993.5 5994.6 5996.9 5862.9 5878.6 6017.8 6067.1 6068.7 5909.9 5967.5 5972.2 5992.1 6006.5 5985.1 5985.2 5987.4 5998.5 6006.5 5536.0 5644.1 5726.6 5756.9 5932.0 5918.5 5919.8 5924.2 5947.8 5948.7 5663.1 5758.3 5773.0 5776.6 5779.8 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#1] ext.html Cycles:180 Average:60493.69 Median:40218.70 stddev:112920.06 (280.8%) stddev-sans-first:113147.19 09:35:00 INFO - PROCESS | 5417 | Values: 568.6 598.7 618.5 637.9 653.8 32253.2 42462.2 43488.0 43594.7 46478.8 44078.8 44320.6 44504.1 44600.8 44699.0 8157.8 8368.2 8906.4 9082.6 9084.2 10562.2 10927.9 10938.3 11190.0 11211.3 10863.9 10935.0 10942.2 10944.8 10951.5 10891.8 10932.3 10933.1 10939.7 10942.7 10865.5 10915.7 10919.0 10927.2 10930.3 42659.8 42937.1 42945.0 42967.4 43086.0 3679.3 3739.2 3874.4 3933.4 4325.3 31261.7 33161.5 33427.5 33496.2 34231.8 4326.2 4404.4 4432.3 4481.1 4601.7 321782.0 328321.0 328946.0 329763.0 331365.0 65628.3 66843.0 66860.3 66894.0 66936.0 70829.0 71212.6 71271.0 71297.6 71325.6 18322.1 18612.4 18621.9 18628.5 18640.9 13058.1 13470.8 13901.6 14106.2 14239.1 20788.4 20795.4 20899.8 20910.8 20991.1 7462.8 7797.6 7834.8 7932.5 8014.4 960.6 1020.2 1032.1 1035.8 1108.6 30926.5 31031.4 31177.5 31205.7 31593.2 1118.5 1139.7 1140.4 1158.3 1166.1 16298.8 16462.3 16498.7 16541.9 16543.0 484785.0 491166.0 491866.0 494484.0 496465.0 58563.7 59333.0 59369.0 59374.0 59426.4 1281.8 1341.9 1344.1 1404.3 1405.0 29691.2 33396.7 33688.7 34673.3 34980.7 56434.0 57512.7 60684.0 60798.0 60867.4 442651.0 447274.0 447683.0 448041.0 448691.0 39334.0 39929.8 40507.6 43375.8 45376.8 48368.5 48376.8 48394.5 48754.8 49249.3 40664.6 41209.6 41660.8 42192.6 43457.0 41473.0 41970.2 42047.0 42229.2 43311.0 42577.0 43435.1 43987.0 44232.1 46583.5 35936.0 37502.6 38211.2 38237.0 38862.2 28768.1 29551.0 29588.7 29591.9 29987.0 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#2] jquery.html Cycles:180 Average:25763.12 Median:23867.90 stddev:62276.27 (260.9%) stddev-sans-first:62421.87 09:35:00 INFO - PROCESS | 5417 | Values: 404.6 409.4 409.9 418.6 431.1 13828.0 18142.4 19205.5 19485.5 19507.7 15491.5 16597.3 18907.1 19214.2 19516.0 5817.4 6000.2 6003.8 6006.6 6024.1 5709.4 5828.4 5830.7 5930.0 5932.9 5826.2 5828.0 5829.7 5834.9 5836.6 5701.8 5823.7 5826.0 5902.9 5950.2 5333.3 5334.9 5335.9 5342.8 5344.3 23069.9 23146.1 23299.8 23782.8 23953.0 2462.2 2521.4 2540.6 3097.7 3255.6 9975.4 10428.8 10462.5 10615.4 14367.8 1835.5 1886.5 1900.2 1952.5 2056.5 81780.6 83598.6 84262.6 84821.0 85419.0 10792.7 10983.5 11137.1 11143.3 11241.5 15516.4 15547.3 15550.3 15554.5 15577.2 7513.5 7891.9 8019.1 8579.9 8729.3 8236.2 8321.4 8332.2 8522.2 8554.9 4633.8 4706.3 4749.6 4832.0 4874.1 1740.2 1836.5 1850.0 2024.8 2033.4 939.3 946.5 1050.7 1052.1 1108.2 19420.4 19609.5 20024.5 20032.6 20357.5 1002.0 1014.4 1025.1 1026.9 1053.3 10661.9 10717.4 11480.1 11933.3 11978.8 331728.0 332008.0 334160.0 424513.0 424900.0 83668.6 88652.6 92634.0 93082.0 94152.5 1034.3 1074.5 1074.9 1075.8 1082.1 17596.6 17857.7 18231.0 18379.9 18540.6 35130.8 41277.4 42105.6 42886.8 42914.6 57889.7 58812.7 59710.0 60190.4 60210.7 8529.0 8588.3 8642.6 8647.9 8877.7 10316.1 11010.4 13079.7 13464.6 13624.0 11118.3 11275.8 11870.9 11876.7 11938.6 11722.5 11776.4 11796.8 11825.0 11884.8 11829.8 11875.2 12008.9 12072.3 12671.6 7535.2 7999.2 8462.4 8620.1 8764.2 11592.3 11731.4 11848.1 11875.9 11980.1 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#3] mootools.html Cycles:180 Average:5931.51 Median:472.18 stddev:3362.75 (712.2%) stddev-sans-first:3355.63 09:35:00 INFO - PROCESS | 5417 | Values: 1491.0 1529.9 1669.8 1797.8 1835.6 8385.2 8963.1 8964.1 8979.1 8986.6 7888.4 8218.1 8568.4 8798.5 8839.3 2484.4 2505.6 2509.8 2514.9 2516.5 2456.4 2537.7 2540.6 2546.7 2547.5 2411.0 2428.8 2517.8 2524.1 2525.9 2393.5 2396.7 2420.4 2426.7 2427.9 2385.3 2388.1 2407.4 2407.8 2435.2 9059.0 9108.9 9111.1 9115.2 9116.9 5401.5 5543.0 5548.6 5550.9 5551.6 3339.9 3379.4 3460.2 3560.9 3588.5 1031.2 1044.4 1050.6 1052.7 1099.9 12640.8 12697.4 12707.0 12711.1 12711.9 4769.2 4848.5 4942.5 4966.2 5022.3 10696.1 10743.9 10747.7 10754.6 10766.0 5582.5 5583.4 5583.8 5585.8 5588.4 4511.1 4776.9 4965.4 5085.1 5135.2 3982.8 4089.9 4139.9 4193.8 4223.3 1294.2 1320.2 1352.8 1354.8 1363.9 942.0 1001.6 1003.7 1011.3 1021.1 5879.6 6013.5 6060.4 6083.1 6091.9 468.2 469.8 474.5 478.7 485.5 3910.0 3916.2 3940.9 3943.0 3954.1 10732.4 11146.4 11149.8 11158.9 11175.9 10990.3 11026.4 11034.9 11039.9 11090.4 8321.4 8454.6 8540.4 8540.4 8557.8 5901.6 5907.7 5950.7 6010.0 6034.2 9785.0 10052.6 10150.1 10165.3 10255.2 10049.1 10133.2 10315.9 10318.5 10319.9 7375.2 8034.2 8394.5 8482.9 8653.1 8586.3 8723.3 8727.1 8728.7 8731.2 7763.3 7866.6 7917.6 7949.1 8080.9 7620.3 7653.0 7760.1 7762.3 7939.9 7173.5 7189.0 7256.2 7441.9 7457.2 5127.0 5232.2 5258.7 5301.4 5318.8 5299.9 5355.0 5409.4 5445.2 5473.7 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#4] prototype.html Cycles:180 Average:4600.31 Median:5206.94 stddev:1312.24 (25.2%) stddev-sans-first:1276.35 09:35:00 INFO - PROCESS | 5417 | Values: 339.3 347.6 355.9 356.8 359.7 5270.0 5296.2 5307.8 5320.9 5373.3 4952.5 5060.3 5150.7 5155.1 5264.9 4141.6 4163.2 4163.8 4165.5 4166.9 4171.8 4173.6 4175.2 4181.1 4193.6 4071.6 4114.2 4119.0 4149.9 4162.4 4102.3 4124.6 4137.8 4141.2 4143.1 3686.1 3686.3 3687.9 3690.9 3695.7 5000.5 5074.9 5109.9 5121.7 5122.2 4683.4 4707.6 4722.5 4724.9 4724.9 5422.6 5425.7 5437.8 5442.6 5443.3 3890.9 4058.9 4059.2 4060.6 4068.5 5605.3 5608.2 5622.7 5635.2 5646.2 4159.2 4164.1 4177.4 4178.2 4183.6 4200.0 4218.8 4226.2 4226.5 4229.3 4566.3 4689.6 4695.8 4710.4 4716.7 5433.5 5440.6 5441.9 5454.3 5482.3 1363.1 1413.1 1421.6 1426.5 1429.8 966.4 973.7 986.0 1004.7 1019.3 5084.7 5086.7 5112.6 5119.7 5130.7 5418.6 5420.7 5430.9 5434.9 5454.8 4900.0 5092.1 5094.9 5132.2 5151.2 2876.5 2884.2 2888.5 2889.5 2896.9 5331.9 5583.6 5613.9 5639.0 5657.5 5382.8 5474.5 5478.3 5488.6 5496.4 5589.6 5613.7 5615.6 5615.9 5620.4 5260.2 5367.3 5409.3 5432.7 5470.8 5377.8 5387.8 5389.0 5393.5 5396.9 5519.7 5597.9 5621.8 5667.8 5680.5 3900.2 4074.6 4119.9 4137.6 4142.7 5634.5 5641.9 5662.8 5679.7 5693.9 5326.3 5523.0 5541.3 5546.5 5546.8 5483.3 5490.9 5495.3 5500.6 5505.7 5258.8 5358.4 5406.8 5440.5 5485.2 5610.4 5611.0 5615.3 5621.4 5622.6 5450.0 5452.9 5476.2 5487.5 5497.4 09:35:00 INFO - PROCESS | 5417 | 09:35:00 INFO - PROCESS | 5417 | [#5] yui.html Cycles:180 Average:17317.33 Median:4489.47 stddev:22286.09 (496.4%) stddev-sans-first:22313.84 09:35:00 INFO - PROCESS | 5417 | Values: 739.7 761.7 790.6 809.3 884.4 14489.4 15213.5 15389.6 15750.0 16201.1 14123.3 14211.6 14487.1 14490.1 14494.2 827.2 849.1 850.3 850.5 850.6 711.8 735.3 848.2 882.9 887.8 916.6 919.0 919.6 920.2 921.7 921.3 922.3 922.7 923.4 924.1 671.9 677.1 677.4 677.9 677.9 15071.5 15327.5 15369.2 15375.4 15380.5 14367.3 14682.3 15032.8 15343.2 15590.4 11636.5 11790.6 11791.8 11935.5 11971.9 3646.3 3659.1 3723.1 3763.1 3883.2 54873.7 61183.7 61206.4 61741.0 62301.0 11279.3 11912.0 12034.3 12200.9 12253.4 21675.8 21691.6 21926.3 22224.9 22331.0 8246.1 8260.8 8271.4 8298.3 8739.6 4257.7 4293.0 4375.2 4486.0 4550.0 3524.3 3592.4 3594.6 3625.3 3657.9 605.6 607.0 613.2 623.5 632.2 743.4 752.5 753.6 763.7 785.7 8727.9 8779.7 9255.4 9675.5 9815.6 685.3 726.0 731.7 738.3 750.1 3995.4 4323.9 4390.0 4397.2 4492.9 84506.0 85955.0 88153.6 89002.0 92097.0 75473.0 75622.6 79251.2 79656.0 80116.0 42005.0 48988.8 58385.0 58768.4 60285.4 9493.9 9596.5 9793.7 9825.5 9834.0 42714.0 43563.3 43694.3 43886.8 45497.0 41645.0 57309.4 58161.1 59830.7 61031.7 12837.1 12949.5 12963.7 12987.4 13594.5 13544.7 14129.3 14369.3 14665.3 14862.7 12738.2 12817.6 13052.9 13581.7 13584.5 12885.9 13583.0 13799.9 13801.1 14159.7 12682.7 12696.9 12710.0 12845.0 12968.6 8873.1 9410.9 9692.5 10369.3 11013.8 10089.3 10965.0 11012.7 11344.5 11358.7 09:35:00 INFO - PROCESS | 5417 | -------- Summary: end -------- 09:35:00 INFO - PROCESS | 5417 | 09:35:01 INFO - TEST-INFO | 5417: exit 0 09:35:01 INFO - TEST-OK | dromaeo_css | took 1116262ms 09:35:01 INFO - TEST-START | kraken 09:35:01 INFO - operating with platform_type : linux_ 09:35:01 INFO - Initialising browser for kraken test... 09:35:01 INFO - TEST-INFO | started process 5720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYo4A81/profile http://localhost:52706/getInfo.html) 09:35:05 INFO - PROCESS | 5720 | __metrics Screen width/height:1600/1200 09:35:05 INFO - PROCESS | 5720 | colorDepth:24 09:35:05 INFO - PROCESS | 5720 | Browser inner width/height: 1024/697 09:35:05 INFO - PROCESS | 5720 | __metrics 09:35:06 INFO - PROCESS | 5720 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:35:06 INFO - PROCESS | 5720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:07 INFO - PROCESS | 5720 | console.error: PushService: 09:35:07 INFO - PROCESS | 5720 | stateChangeProcessEnqueue: Error transitioning state 09:35:07 INFO - PROCESS | 5720 | UnknownError 09:35:07 INFO - TEST-INFO | 5720: exit 0 09:35:07 INFO - Browser initialized. 09:35:07 INFO - Running cycle 1/1 for kraken test... 09:35:07 INFO - TEST-INFO | started process 5812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYo4A81/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:19 INFO - PROCESS | 5812 | RSS: Main: 141467648 09:35:19 INFO - PROCESS | 5812 | 09:35:22 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52706/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:22 INFO - PROCESS | 5812 | RSS: Main: 182890496 09:35:22 INFO - PROCESS | 5812 | 09:35:25 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52706/tests/kraken/driver.html?testName=audio-dft) 09:35:26 INFO - PROCESS | 5812 | RSS: Main: 173289472 09:35:26 INFO - PROCESS | 5812 | 09:35:28 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52706/tests/kraken/driver.html?testName=audio-fft) 09:35:29 INFO - PROCESS | 5812 | RSS: Main: 263868416 09:35:29 INFO - PROCESS | 5812 | 09:35:31 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52706/tests/kraken/driver.html?testName=audio-oscillator) 09:35:32 INFO - PROCESS | 5812 | RSS: Main: 170950656 09:35:32 INFO - PROCESS | 5812 | 09:35:34 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52706/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:34 INFO - PROCESS | 5812 | RSS: Main: 173387776 09:35:34 INFO - PROCESS | 5812 | 09:35:37 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52706/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:38 INFO - PROCESS | 5812 | RSS: Main: 212475904 09:35:38 INFO - PROCESS | 5812 | 09:35:41 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52706/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:42 INFO - PROCESS | 5812 | RSS: Main: 211296256 09:35:42 INFO - PROCESS | 5812 | 09:35:44 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52706/tests/kraken/driver.html?testName=json-parse-financial) 09:35:45 INFO - PROCESS | 5812 | RSS: Main: 209457152 09:35:45 INFO - PROCESS | 5812 | 09:35:46 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52706/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:47 INFO - PROCESS | 5812 | RSS: Main: 172208128 09:35:47 INFO - PROCESS | 5812 | 09:35:49 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:49 INFO - PROCESS | 5812 | RSS: Main: 178970624 09:35:49 INFO - PROCESS | 5812 | 09:35:51 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:52 INFO - PROCESS | 5812 | RSS: Main: 188133376 09:35:52 INFO - PROCESS | 5812 | 09:35:54 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:55 INFO - PROCESS | 5812 | RSS: Main: 196657152 09:35:55 INFO - PROCESS | 5812 | 09:35:58 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:58 INFO - PROCESS | 5812 | RSS: Main: 192491520 09:35:58 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | Cycle 1(1): loaded http://localhost:52706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52706/tests/kraken/driver.html?testName=ai-astar) 09:36:00 INFO - PROCESS | 5812 | RSS: Main: 244637696 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | __start_tp_report 09:36:00 INFO - PROCESS | 5812 | _x_x_mozilla_page_load 09:36:00 INFO - PROCESS | 5812 | _x_x_mozilla_page_load_details 09:36:00 INFO - PROCESS | 5812 | |i|pagename|runs| 09:36:00 INFO - PROCESS | 5812 | |0;ai-astar;101;110;111;110;111;111;111;111;111;110 09:36:00 INFO - PROCESS | 5812 | |1;audio-beat-detection;141;117;117;160;118;119;116;124;149;118 09:36:00 INFO - PROCESS | 5812 | |2;audio-dft;160;161;160;160;162;161;162;161;162;161 09:36:00 INFO - PROCESS | 5812 | |3;audio-fft;74;75;74;117;76;76;75;76;104;74 09:36:00 INFO - PROCESS | 5812 | |4;audio-oscillator;82;81;117;81;81;83;101;82;81;109 09:36:00 INFO - PROCESS | 5812 | |5;imaging-gaussian-blur;116;117;116;116;116;117;116;117;117;116 09:36:00 INFO - PROCESS | 5812 | |6;imaging-darkroom;147;145;145;146;146;145;148;146;146;147 09:36:00 INFO - PROCESS | 5812 | |7;imaging-desaturate;85;84;85;85;85;84;84;85;85;84 09:36:00 INFO - PROCESS | 5812 | |8;json-parse-financial;51;50;50;52;49;53;50;51;51;50 09:36:00 INFO - PROCESS | 5812 | |9;json-stringify-tinderbox;61;59;58;58;59;60;63;58;60;59 09:36:00 INFO - PROCESS | 5812 | |10;stanford-crypto-aes;73;77;79;73;75;74;80;75;74;74 09:36:00 INFO - PROCESS | 5812 | |11;stanford-crypto-ccm;126;124;121;137;119;122;120;119;166;124 09:36:00 INFO - PROCESS | 5812 | |12;stanford-crypto-pbkdf2;156;158;156;155;162;155;153;156;158;157 09:36:00 INFO - PROCESS | 5812 | |13;stanford-crypto-sha256-iterative;67;64;63;63;62;62;64;61;62;62 09:36:00 INFO - PROCESS | 5812 | __end_tp_report 09:36:00 INFO - PROCESS | 5812 | __start_cc_report 09:36:00 INFO - PROCESS | 5812 | _x_x_mozilla_cycle_collect,1639 09:36:00 INFO - PROCESS | 5812 | __end_cc_report 09:36:00 INFO - PROCESS | 5812 | __startTimestamp1487266560761__endTimestamp 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | ------- Summary: start ------- 09:36:00 INFO - PROCESS | 5812 | Number of tests: 14 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.09 (2.8%) stddev-sans-first:0.50 09:36:00 INFO - PROCESS | 5812 | Values: 101.0 110.0 111.0 110.0 111.0 111.0 111.0 111.0 111.0 110.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.50 stddev:16.04 (13.2%) stddev-sans-first:16.30 09:36:00 INFO - PROCESS | 5812 | Values: 141.0 117.0 117.0 160.0 118.0 119.0 116.0 124.0 149.0 118.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 09:36:00 INFO - PROCESS | 5812 | Values: 160.0 161.0 160.0 160.0 162.0 161.0 162.0 161.0 162.0 161.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:15.30 (20.4%) stddev-sans-first:15.95 09:36:00 INFO - PROCESS | 5812 | Values: 74.0 75.0 74.0 117.0 76.0 76.0 75.0 76.0 104.0 74.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:81.00 stddev:13.79 (17.0%) stddev-sans-first:14.34 09:36:00 INFO - PROCESS | 5812 | Values: 82.0 81.0 117.0 81.0 81.0 83.0 101.0 82.0 81.0 109.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:36:00 INFO - PROCESS | 5812 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 116.0 117.0 117.0 116.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 09:36:00 INFO - PROCESS | 5812 | Values: 147.0 145.0 145.0 146.0 146.0 145.0 148.0 146.0 146.0 147.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 09:36:00 INFO - PROCESS | 5812 | Values: 85.0 84.0 85.0 85.0 85.0 84.0 84.0 85.0 85.0 84.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.16 (2.3%) stddev-sans-first:1.22 09:36:00 INFO - PROCESS | 5812 | Values: 51.0 50.0 50.0 52.0 49.0 53.0 50.0 51.0 51.0 50.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:1.58 (2.7%) stddev-sans-first:1.58 09:36:00 INFO - PROCESS | 5812 | Values: 61.0 59.0 58.0 58.0 59.0 60.0 63.0 58.0 60.0 59.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:2.46 (3.3%) stddev-sans-first:2.45 09:36:00 INFO - PROCESS | 5812 | Values: 73.0 77.0 79.0 73.0 75.0 74.0 80.0 75.0 74.0 74.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:124.00 stddev:14.42 (11.6%) stddev-sans-first:15.28 09:36:00 INFO - PROCESS | 5812 | Values: 126.0 124.0 121.0 137.0 119.0 122.0 120.0 119.0 166.0 124.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.60 Median:156.50 stddev:2.41 (1.5%) stddev-sans-first:2.55 09:36:00 INFO - PROCESS | 5812 | Values: 156.0 158.0 156.0 155.0 162.0 155.0 153.0 156.0 158.0 157.0 09:36:00 INFO - PROCESS | 5812 | 09:36:00 INFO - PROCESS | 5812 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.70 (2.7%) stddev-sans-first:1.01 09:36:00 INFO - PROCESS | 5812 | Values: 67.0 64.0 63.0 63.0 62.0 62.0 64.0 61.0 62.0 62.0 09:36:00 INFO - PROCESS | 5812 | -------- Summary: end -------- 09:36:00 INFO - PROCESS | 5812 | 09:36:01 INFO - TEST-INFO | 5812: exit 0 09:36:01 INFO - TEST-OK | kraken | took 60098ms 09:36:01 INFO - SUITE-END | took 1176s 09:36:01 INFO - Completed test suite (00:19:36) 09:36:01 INFO - javascript benchmark 09:36:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3203.4554125798613, 3221.3879362921057, 3235.3529294141176, 3255.6581558936296, 3264.249222678784, 5523.806666766664, 5655.066913959192, 5661.094224924013, 5667.8583035424135, 5702.828915132547, 5544.944550554494, 5562.110062390018, 5579.497845193931, 5595.916061259084, 5624.437556244374, 5009.849704508862, 5010.273510340999, 5052.343195384595, 5094.923576146342, 5135.871603209912, 5016.47327030662, 5095.79616815327, 5148.2020286855595, 5153.871153221162, 5155.871103222412, 4994.200927851544, 5015.774290156934, 5027.396712394499, 5049.939512702337, 5052.671576347544, 5022.573081288095, 5117.053345131163, 5123.923141152848, 5132.563732082777, 5156.381234251876, 4968.378952630921, 5047.0, 5069.214271787879, 5087.720175390389, 5089.643724939256, 5435.211894275348, 5486.643368181056, 5502.532284755801, 5564.054110801174, 5571.220029195916, 5792.507636850873, 5802.88394232113, 5804.59367844251, 5805.564582656318, 5820.82537523875, 5952.821415357503, 5969.492593129589, 5970.283565972068, 5973.6415815050805, 5976.402359764033, 5453.836384908459, 5483.122700367922, 5483.890322193533, 5534.252875861787, 5557.360903496109, 6095.634261944208, 6117.143599896018, 6117.143599896018, 6125.418085281891, 6136.938630613726, 5861.531077513789, 5867.853303667357, 5869.765209391577, 5874.0, 5880.0591905295805, 5759.539236861041, 5803.88392232153, 5866.032104702762, 5906.793262235801, 5926.2295901532525, 5803.129530570448, 5821.53427725781, 5838.211841401357, 5839.124131380242, 5845.766169353264, 5778.88442231153, 5822.883542329129, 5829.766809327664, 5831.533477321804, 5834.883302333929, 4543.68194226401, 4549.499555048942, 4573.6569757268335, 4620.838270660511, 4634.536546345338, 4135.875923722293, 4148.0666849959125, 4148.79256037203, 4176.937345939813, 4193.706440549193, 5779.422057794271, 5790.768369265266, 5802.303723553201, 5802.82591522255, 5823.621464604872, 5885.646861188342, 5956.2852457704485, 6118.388161183935, 6122.265328160649, 6125.173101631222, 5568.805091821767, 5583.888322233533, 5594.524465420485, 5658.688772117573, 5674.148877668383, 5085.3134826797905, 5091.490850914879, 5093.1087059764395, 5099.923501147486, 5109.489051094935, 6087.543434242538, 6137.232845894263, 6144.188132131007, 6150.569460137747, 6180.103884936659, 5900.468957793648, 5969.462748352669, 5986.970065149821, 6019.187409699634, 6128.571000029955, 6248.843778905563, 6337.176167098248, 6340.556161068681, 6341.714622841891, 6343.809685709436, 5984.611000284967, 5987.191729116687, 5993.460588546877, 5994.640321580545, 5996.910046349395, 5862.94137058624, 5878.588498805043, 6017.849553761191, 6067.120267561268, 6068.666223357758, 5909.85225369352, 5967.49276311501, 5972.163897054329, 5992.131140984446, 6006.459418652167, 5985.072313791369, 5985.221921150222, 5987.43119403656, 5998.460138587374, 6006.519478441802, 5536.0, 5644.125160600113, 5726.627769194989, 5756.856078598069, 5931.970340148272, 5918.526517878473, 5919.763209471573, 5924.170616113834, 5947.762089516371, 5948.732307046107, 5663.065594176915, 5758.337791153904, 5773.0, 5776.624519406225, 5779.826605201851], "value": 5509.279405762199, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.6417556939127, 598.6856900127433, 618.4959258204565, 637.9489640828734, 653.8038588423472, 32253.193670158256, 42462.15075698488, 43488.0, 43594.73039522033, 46478.76760616201, 44078.779606102005, 44320.55679443204, 44504.10991780162, 44600.776996115055, 44699.0, 8157.75526734199, 8368.163183681643, 8906.376553641248, 9082.59128339223, 9084.182423581877, 10562.155027597773, 10927.890721092766, 10938.289011214285, 11190.048845848112, 11211.327320360764, 10863.945680271587, 10935.0, 10942.17933654975, 10944.781104377907, 10951.45242737864, 10891.83662245067, 10932.289401188897, 10933.125349971982, 10939.671809845717, 10942.726431839228, 10865.51105200268, 10915.727106822314, 10919.0, 10927.235093543455, 10930.289531180528, 42659.786701066296, 42937.141257174684, 42945.0, 42967.35548966768, 43086.0, 3679.337719210541, 3739.158689294914, 3874.380099184117, 3933.409988501719, 4325.329573916048, 31261.687383126104, 33161.50257746136, 33427.49858752121, 33496.16259593505, 34231.82884085589, 4326.156399502099, 4404.427424434835, 4432.335149727534, 4481.148581769454, 4601.677882548223, 321782.0, 328321.0, 328946.0, 329762.9999999976, 331365.0, 65628.34371656318, 66843.0, 66860.33139668638, 66894.0, 66936.0, 70829.0, 71212.64393678102, 71271.0, 71297.64351178211, 71325.64337178385, 18322.083895805157, 18612.44162675122, 18621.906890465732, 18628.534286642673, 18640.906795466206, 13058.08593398472, 13470.797938030937, 13901.58295251144, 14106.224157671426, 14239.074460160056, 20788.376348709564, 20795.376138715863, 20899.79100209009, 20910.790892091187, 20991.055402506925, 7462.776116716507, 7797.649105790253, 7834.8433031339055, 7932.524048557105, 8014.358851291882, 960.6109525642127, 1020.1838529176629, 1032.0711359776262, 1035.7876635289747, 1108.578740078765, 30926.53610195849, 31031.44897928771, 31177.532337014964, 31205.68794312073, 31593.210169745482, 1118.4799068433056, 1139.6922830835629, 1140.401289323112, 1158.2760774515928, 1166.102101381931, 16298.837011629968, 16462.341506339853, 16498.67002659964, 16541.917290413472, 16543.0, 484785.0, 491166.0, 491866.0, 494484.0, 496465.0, 58563.70718146382, 59333.0, 59369.0, 59374.0, 59426.40573594295, 1281.8461784585909, 1341.8792308692068, 1344.1196016609138, 1404.3189053309136, 1404.985950140506, 29691.239827668705, 33396.66603334033, 33688.66311336855, 34673.30653386968, 34980.65019349876, 56434.0, 57512.71243643755, 60684.0, 60798.0, 60867.391326086174, 442651.0, 447274.0, 447683.0, 448041.0, 448691.0, 39334.0, 39929.80035099806, 40507.59492405038, 43375.78312108419, 45376.77311613421, 48368.5163148364, 48376.7581162106, 48394.51605484041, 48754.75622621864, 49249.2612610804, 40664.59335406608, 41209.58790412058, 41660.79169604133, 42192.578074218865, 43457.0, 41473.0, 41970.16059678728, 42047.0, 42229.15541689211, 43311.0, 42577.0, 43435.13129737451, 43987.0, 44232.11535769202, 46583.50623703168, 35936.0, 37502.62497374992, 38211.23577528489, 38237.0, 38862.2227555453, 28768.136955891358, 29551.0, 29588.704112958596, 29591.85204074052, 29987.0], "value": 21446.128881496246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.61965752192935, 409.42271397329773, 409.89342770879574, 418.62323908482364, 431.1033051253393, 13828.0, 18142.367186953186, 19205.51986200342, 19485.512862178453, 19507.70738438923, 15491.457798977039, 16597.336106555755, 18907.149178286974, 19214.231430742755, 19516.0, 5817.447342502455, 6000.189974353472, 6003.789867354643, 6006.579539432241, 6024.09638554216, 5709.371969083419, 5828.388019257981, 5830.708464576775, 5930.021546444831, 5932.940670593303, 5826.242588463494, 5828.038373668361, 5829.650220986754, 5834.854128646775, 5836.649801011931, 5701.828945131652, 5823.67969761663, 5825.970870145643, 5902.911456328158, 5950.166976623276, 5333.253344531769, 5334.866628334284, 5335.866603334947, 5342.786288548454, 5344.278522399465, 23069.88465057681, 23146.074157033698, 23299.76700232993, 23782.7621723784, 23953.041878324846, 2462.175171317611, 2521.3948652323493, 2540.593505039185, 3097.725139307741, 3255.576775019232, 9975.351602145838, 10428.843567346496, 10462.47687615616, 10615.363078215332, 14367.78448323276, 1835.4677143628464, 1886.509507528039, 1900.220909427142, 1952.492351988479, 2056.5269987902866, 81780.59109704473, 83598.58200708957, 84262.57868710678, 84821.0, 85419.0, 10792.730181745363, 10983.505742241618, 11137.109031277478, 11143.275687080313, 11241.494132764044, 15516.37934482608, 15547.30037148331, 15550.300236489385, 15554.45559405438, 15577.221138943007, 7513.511621744569, 7891.9221503613635, 8019.117897031322, 8579.871301930476, 8729.345299102466, 8236.217559331853, 8321.375896807642, 8332.16678332174, 8522.190391912758, 8554.871676924851, 4633.814647414133, 4706.317583950308, 4749.62003039756, 4831.95168048322, 4874.122657921537, 1740.1647209339335, 1836.4513191095207, 1850.0009994603033, 2024.7570291565107, 2033.3594917600908, 939.2720641502754, 946.4557879219476, 1050.7005503431485, 1052.084686322904, 1108.1854836695022, 19420.417387478115, 19609.509762256057, 20024.49938751543, 20032.59934801296, 20357.491062723253, 1001.9599216031423, 1014.4167103915131, 1025.1388833379847, 1026.8870424253325, 1053.346924906563, 10661.89338106609, 10717.410542420086, 11480.138989575815, 11933.284002959677, 11978.820317695241, 331728.0, 332008.0, 334160.0, 424513.0, 424900.0, 83668.58165709254, 88652.5567372159, 92634.0, 93082.0, 94152.52923735474, 1034.343192073039, 1074.4842475611595, 1074.8874914463493, 1075.7525769073156, 1082.1451053667672, 17596.560085997953, 17857.73213401774, 18231.0, 18379.90810045941, 18540.629187415907, 35130.82434587811, 41277.38083928804, 42105.57894421139, 42886.78556607197, 42914.57085429106, 57889.71055144697, 58812.70593647005, 59710.0, 60190.39809602023, 60210.698946504985, 8528.957355213432, 8588.315027807153, 8642.611082501428, 8647.913520864711, 8877.689280875138, 10316.123129533773, 11010.39442830652, 13079.673008174872, 13464.596062118153, 13624.0, 11118.332900026024, 11275.830862537232, 11870.940645296718, 11876.703082423008, 11938.582149625068, 11722.531098755955, 11776.411179441166, 11796.823047654463, 11825.0, 11884.821727674265, 11829.763404731684, 11875.16873818859, 12008.939955300517, 12072.3360215189, 12671.556495522613, 7535.246475352421, 7999.160088190656, 8462.407631465738, 8620.13798620133, 8764.21122099014, 11592.304461732323, 11731.41342932867, 11848.111391645833, 11875.881241187823, 11980.101492387932], "value": 9281.2698137282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1490.9925450372746, 1529.8776097912164, 1669.7996240451148, 1797.8022417534078, 1835.6328734253145, 8385.161483851613, 8963.058878817712, 8964.103589641032, 8979.102089791026, 8986.640534378634, 7888.368930485562, 8218.054923683778, 8568.443051201682, 8798.472091674505, 8839.337049721264, 2484.3664865459327, 2505.6366826810104, 2509.77412032917, 2514.9119780807673, 2516.471540976397, 2456.361346050031, 2537.6701028866223, 2540.5808041673104, 2546.707128680207, 2547.5159719653298, 2410.9758902411013, 2428.805695544352, 2517.8363406378617, 2524.1417917907906, 2525.873706314686, 2393.5093305872256, 2396.6764486794323, 2420.3707036170545, 2426.6845310109748, 2427.8543287402813, 2385.2963375804006, 2388.092524840567, 2407.3981504623844, 2407.807375409963, 2435.2450740270397, 9058.954705226497, 9108.8633670495, 9111.088891110901, 9115.22520585751, 9116.908830911672, 5401.4868587484125, 5543.0, 5548.639338442988, 5550.86122846928, 5551.639143455663, 3339.9499007514905, 3379.4086034944035, 3460.2214501737158, 3560.928781424383, 3588.5334906461994, 1031.2059714020238, 1044.3577200021932, 1050.5535147562255, 1052.726291164303, 1099.8790133085354, 12640.810387844189, 12697.428615712315, 12706.983441324764, 12711.110222284444, 12711.872881271162, 4769.165396055676, 4848.490908454632, 4942.505749425029, 4966.180580204225, 5022.321986531844, 10696.144308455305, 10743.892561074446, 10747.677569672922, 10754.569817207224, 10766.0, 5582.469665381782, 5583.413741557078, 5583.804566840141, 5585.832425027255, 5588.385277619459, 4511.142882852248, 4776.928346074812, 4965.4041515018425, 5085.1355269604255, 5135.178371460623, 3982.761034337949, 4089.9386509202386, 4139.875803725833, 4193.811278492475, 4223.324268117087, 1294.184663661887, 1320.2078752748275, 1352.8241328627216, 1354.7832346824462, 1363.8908887288992, 942.0, 1001.6495419043156, 1003.7440452684518, 1011.2617789013914, 1021.1473419694489, 5879.617824841457, 6013.54898382623, 6060.393960603904, 6083.148359229712, 6091.939080609226, 468.23911144390365, 469.8214678422246, 474.54206690543793, 478.74626447982627, 485.4902352529829, 3909.980450097788, 3916.2167566486814, 3940.930698768379, 3942.9802850986134, 3954.0905591714068, 10732.356058636351, 11146.442677866075, 11149.832752508719, 11158.888411115948, 11175.94412027951, 10990.285631433931, 11026.44867756609, 11034.889651103385, 11039.889601104047, 11090.390028548507, 8321.417500774887, 8454.61954212062, 8540.359473039547, 8540.44487108336, 8557.828843423096, 5901.5868889177345, 5907.734151963086, 5950.732217050157, 6010.0083486224285, 6034.155218269358, 9785.0, 10052.64815731446, 10150.086492215736, 10165.288429809842, 10255.179585633281, 10049.095581397709, 10133.18934485254, 10315.896841031494, 10318.48407579633, 10319.896801031893, 7375.188729239887, 8034.236747509211, 8394.496330220207, 8482.915170848213, 8653.134686531297, 8586.270167035871, 8723.302135829246, 8727.127287271222, 8728.738137855875, 8731.170538798804, 7763.340116090196, 7866.6066696666085, 7917.604119793872, 7949.085855126735, 8080.9191908080165, 7620.314171724572, 7653.0, 7760.146383897654, 7762.301392874669, 7939.880901786362, 7173.5337203081635, 7189.0, 7256.1655409626455, 7441.851162976602, 7457.216992215739, 5127.0, 5232.162853943503, 5258.7107709074835, 5301.390340110936, 5318.760655770422, 5299.887023724907, 5354.973225133849, 5409.404965453718, 5445.1832225166545, 5473.698946557977], "value": 4682.1691304204305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.25364198762713, 347.6210930086206, 355.8861164427383, 356.8269389346167, 359.74458134724347, 5270.0, 5296.179092240704, 5307.840764777063, 5320.856015956569, 5373.328333958255, 4952.479989601094, 5060.291559181717, 5150.7424628768595, 5155.097857874866, 5264.8937127095005, 4141.585841415849, 4163.16820598937, 4163.791810409482, 4165.479315085614, 4166.854160104389, 4171.812268447927, 4173.603507666767, 4175.227582897159, 4181.08016236429, 4193.580641935797, 4071.5724848890886, 4114.189756507142, 4119.0, 4149.917001659965, 4162.354835000579, 4102.343625020012, 4124.566920473337, 4137.820721094474, 4141.233871733723, 4143.129942712034, 3686.078480379905, 3686.318031164243, 3687.9262414751556, 3690.944635830465, 3695.7228207884245, 5000.549950504472, 5074.919283093951, 5109.897802043938, 5121.743912804345, 5122.231665250205, 4683.437987441494, 4707.646926480494, 4722.5442769759875, 4724.905501889977, 4724.929126063112, 5422.593305502103, 5425.674459532401, 5437.8368648940595, 5442.6462279951675, 5443.346798384219, 3890.8832735017995, 4058.9188216235807, 4059.1733719826198, 4060.553339132693, 4068.5182038956214, 5605.2713147291, 5608.2428872102155, 5622.662640241599, 5635.154726790973, 5646.237757902672, 4159.168166366739, 4164.104717485755, 4177.415161877339, 4178.185253875526, 4183.602557757008, 4200.033992181754, 4218.8312467500955, 4226.2392769301205, 4226.5139508956245, 4229.281022226229, 4566.26939689648, 4689.601383882408, 4695.81216751333, 4710.411198600175, 4716.679329787665, 5433.4566543345345, 5440.591955603346, 5441.918371224435, 5454.290942177491, 5482.259894914216, 1363.1412210307642, 1413.1379858286437, 1421.6090575091725, 1426.543506078045, 1429.7640889253157, 966.367029595616, 973.6786860336072, 985.9802803944025, 1004.7490873862089, 1019.3425240719695, 5084.694918304914, 5086.669366491166, 5112.616553758408, 5119.667221630582, 5130.7178105204575, 5418.647787893774, 5420.701861397582, 5430.8819560371485, 5434.8913021739345, 5454.836354909359, 4899.975500122477, 5092.134337162691, 5094.923576146361, 5132.230165475208, 5151.175811870083, 2876.4678534470772, 2884.206843118159, 2888.508953477914, 2889.4510043091746, 2896.9420611587652, 5331.946680533145, 5583.553315734732, 5613.887722245532, 5639.0244877361365, 5657.4908258256955, 5382.838514844561, 5474.467149198231, 5478.260434841245, 5488.643238189585, 5496.367917689517, 5589.636673616202, 5613.663180209201, 5615.634983725963, 5615.859603509945, 5620.381758006616, 5260.237820330232, 5367.329083864517, 5409.323834520685, 5432.674039557639, 5470.753816078206, 5377.784888604412, 5387.784488620411, 5388.973055134699, 5393.460653934575, 5396.892062158657, 5519.724013799374, 5597.944020559742, 5621.775128994795, 5667.801626943202, 5680.517156041783, 3900.219956008866, 4074.633283004544, 4119.876403707894, 4137.627613514798, 4142.689298302579, 5634.478690753392, 5641.8589535261945, 5662.8018019370775, 5679.659220446786, 5693.857653558694, 5326.280952071421, 5522.978249023943, 5541.284770462364, 5546.500814926676, 5546.833594992157, 5483.314585676791, 5490.945090549043, 5495.2856128703015, 5500.614956952975, 5505.724713764215, 5258.791307650719, 5358.4105748366865, 5406.756695948613, 5440.483154100354, 5485.204645326326, 5610.410906854721, 5611.018071837494, 5615.326160860723, 5621.353544342453, 5622.550195984393, 5449.972750136224, 5452.945470545244, 5476.17857321405, 5487.506124448659, 5497.39528651856], "value": 4190.943222607756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.6745432009916, 761.7067429039818, 790.5533373643894, 809.2676128104068, 884.3544212724711, 14489.420423183074, 15213.467528636485, 15389.615259618515, 15750.0, 16201.10893900835, 14123.293835308245, 14211.573652790406, 14487.058341207814, 14490.130592164478, 14494.202818844959, 827.1563005734163, 849.1254008371357, 850.2560259772699, 850.4727069217095, 850.6427300533791, 711.7935798618421, 735.312482828557, 848.2365870716343, 882.9337799665019, 887.8179973105498, 916.6241840845255, 918.9770255743626, 919.5769945824928, 920.1718453391934, 921.7280902133853, 921.2860033474111, 922.2714055895834, 922.7370199493113, 923.4413180026088, 924.1036194890945, 671.8924972004456, 677.0960767375559, 677.3531277629901, 677.8542613338155, 677.864427114579, 15071.54785356441, 15327.463538776201, 15369.231538423033, 15375.384984600603, 15380.538583842503, 14367.281635918162, 14682.265886705622, 15032.774508382383, 15343.232838358037, 15590.376384944702, 11636.534538618445, 11790.646280611596, 11791.823122653168, 11935.462904169246, 11971.880281197164, 3646.2707458508403, 3659.0852286928266, 3723.106454450912, 3763.1156678180605, 3883.165119499321, 54873.725631371584, 61183.69408152975, 61206.38793612051, 61740.99999999955, 62301.0, 11279.266847654877, 11912.0, 12034.338111403782, 12200.877991220152, 12253.448594813255, 21675.783242167377, 21691.566168676854, 21926.342209733735, 22224.88887555552, 22331.0, 8246.134155913664, 8260.793480163044, 8271.439060572398, 8298.336133109337, 8739.60671769772, 4257.723247988871, 4293.0, 4375.234333991539, 4486.0, 4549.977250113794, 3524.2951409718157, 3592.3533763922746, 3594.6045934947133, 3625.3474374612824, 3657.8902632921054, 605.5821483176594, 606.9878602427926, 613.2181468627447, 623.5198896849491, 632.165541485235, 743.3941337809775, 752.5146280648951, 753.6344872736719, 763.6907052643688, 785.6935795039939, 8727.909011373578, 8779.736607901645, 9255.444673319487, 9675.467849268358, 9815.607375705034, 685.2873012067394, 725.9782206533813, 731.6780616528711, 738.346563291491, 750.1036261667256, 3995.380715989026, 4323.935140972888, 4389.978050109729, 4397.1865204937185, 4492.932606010912, 84506.0, 85955.0, 88153.55923220344, 89002.0, 92097.0, 75473.0, 75622.6218868902, 79251.20748792554, 79656.0, 80116.0, 42004.99999999939, 48988.755056224494, 58385.0, 58768.41231587715, 60285.39714602885, 9493.905060949302, 9596.472194029395, 9793.706188814347, 9825.5087245636, 9834.0, 42714.0, 43563.34654980241, 43694.344584830615, 43886.78056609697, 45497.0, 41645.0, 57309.42690573041, 58161.12758308544, 59830.70084649549, 61031.69484152551, 12837.101402901708, 12949.54676586315, 12963.675908102374, 12987.41556629935, 13594.524191653245, 13544.661383465493, 14129.293535323399, 14369.281535922953, 14665.266736663338, 14862.702745945237, 12738.172018818746, 12817.615471535868, 13052.934735326262, 13581.660458488617, 13584.524541640994, 12885.935570322088, 13583.0, 13799.862001379857, 13801.102928309629, 14159.716805664035, 12682.682932926382, 12696.936515317364, 12710.046746493865, 12845.0, 12968.610941671765, 8873.112688731333, 9410.905890941003, 9692.515374231401, 10369.274150809371, 11013.779724405307, 10089.344192627455, 10965.0, 11012.669619911416, 11344.489497972447, 11358.659240222807], "value": 7222.673072764211, "unit": "score"}], "name": "dromaeo_css", "value": 7332.619636743665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 111.0, 110.0, 111.0, 111.0, 111.0, 111.0, 111.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 117.0, 160.0, 118.0, 119.0, 116.0, 124.0, 149.0, 118.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 162.0, 161.0, 162.0, 161.0, 162.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 117.0, 76.0, 76.0, 75.0, 76.0, 104.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 117.0, 81.0, 81.0, 83.0, 101.0, 82.0, 81.0, 109.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 146.0, 145.0, 148.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 84.0, 85.0, 85.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 52.0, 49.0, 53.0, 50.0, 51.0, 51.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 58.0, 59.0, 60.0, 63.0, 58.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 79.0, 73.0, 75.0, 74.0, 80.0, 75.0, 74.0, 74.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 124.0, 121.0, 137.0, 119.0, 122.0, 120.0, 119.0, 166.0, 124.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 156.0, 155.0, 162.0, 155.0, 153.0, 156.0, 158.0, 157.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 63.0, 62.0, 62.0, 64.0, 61.0, 62.0, 62.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1450.6000000000001, "alertThreshold": 2.0}]} 09:36:01 INFO - Return code: 0 09:36:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:02 INFO - # TBPL SUCCESS # 09:36:02 INFO - Running post-action listener: _resource_record_post_action 09:36:02 INFO - [mozharness: 2017-02-16 17:36:02.057322Z] Finished run-tests step (success) 09:36:02 INFO - Running post-run listener: _resource_record_post_run 09:36:02 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 320757760; Read time: 0; Write time: 243936 09:36:02 INFO - TinderboxPrint: CPU usage
13.0% 09:36:02 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:36:02 INFO - TinderboxPrint: I/O write bytes / time
320,757,760 / 243,936 09:36:02 INFO - TinderboxPrint: CPU idle
8,265.3 (87.0%) 09:36:02 INFO - TinderboxPrint: CPU user
1,190.0 (12.5%) 09:36:02 INFO - TinderboxPrint: Swap in / out
0 / 0 09:36:02 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 101289984; Read time: 0; Write time: 121544 09:36:02 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 219467776; Read time: 0; Write time: 122392 09:36:02 INFO - Running post-run listener: _upload_blobber_files 09:36:02 INFO - Blob upload gear active. 09:36:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:36:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:36:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:03 INFO - (blobuploader) - INFO - Done attempting. 09:36:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:07 INFO - (blobuploader) - INFO - Done attempting. 09:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:08 INFO - (blobuploader) - INFO - Done attempting. 09:36:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:08 INFO - Return code: 0 09:36:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/63e7f45605b7f9d6d31d8efb837536f1cf76e47492f821e9545a162a8733c9837576b59e0fd5d6f3c178f5c0ec2136302459cc30d364e525b62b9a3e54a522ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b9ed0a1fb7d3b5d4c2f376b54095067966d4da04d94aaf7db6735661a06bdea4d83085ee0f50c89e3b7550e830bb515d3e67885e98e67b814da1faa2083f832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acc801abe1f23ff12803011e66247d79810bb39d610e902d96bba2902392406718d565806af582f478a6c7dc4ad5056b3a13f0cf6c1d1d34451bd789667fd9aa"} 09:36:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:08 INFO - Contents: 09:36:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/63e7f45605b7f9d6d31d8efb837536f1cf76e47492f821e9545a162a8733c9837576b59e0fd5d6f3c178f5c0ec2136302459cc30d364e525b62b9a3e54a522ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b9ed0a1fb7d3b5d4c2f376b54095067966d4da04d94aaf7db6735661a06bdea4d83085ee0f50c89e3b7550e830bb515d3e67885e98e67b814da1faa2083f832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acc801abe1f23ff12803011e66247d79810bb39d610e902d96bba2902392406718d565806af582f478a6c7dc4ad5056b3a13f0cf6c1d1d34451bd789667fd9aa"} 09:36:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:08 INFO - Copying logs to upload dir... 09:36:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.092493 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-02-16 09:36:07.601441) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:36:07.604845) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/63e7f45605b7f9d6d31d8efb837536f1cf76e47492f821e9545a162a8733c9837576b59e0fd5d6f3c178f5c0ec2136302459cc30d364e525b62b9a3e54a522ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b9ed0a1fb7d3b5d4c2f376b54095067966d4da04d94aaf7db6735661a06bdea4d83085ee0f50c89e3b7550e830bb515d3e67885e98e67b814da1faa2083f832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acc801abe1f23ff12803011e66247d79810bb39d610e902d96bba2902392406718d565806af582f478a6c7dc4ad5056b3a13f0cf6c1d1d34451bd789667fd9aa"} build_url:https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/SJihPO9gQZmF9hB8HqA4iA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/63e7f45605b7f9d6d31d8efb837536f1cf76e47492f821e9545a162a8733c9837576b59e0fd5d6f3c178f5c0ec2136302459cc30d364e525b62b9a3e54a522ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b9ed0a1fb7d3b5d4c2f376b54095067966d4da04d94aaf7db6735661a06bdea4d83085ee0f50c89e3b7550e830bb515d3e67885e98e67b814da1faa2083f832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acc801abe1f23ff12803011e66247d79810bb39d610e902d96bba2902392406718d565806af582f478a6c7dc4ad5056b3a13f0cf6c1d1d34451bd789667fd9aa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-16 09:36:07.630039) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:36:07.630459) ========= 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=0a54c299e5ca1d63338479f2000001b5-1487265330.945845-1403903577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004527 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-16 09:36:07.713219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:36:07.713543) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-16 09:36:07.713849) ========= ========= Total master_lag: 0.16 =========