builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1479145713.58 results: success (0) buildid: 20161114043455 builduid: d00525730d904e358afe39be231f63da revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.578919) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.579402) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.579680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.594546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.594932) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 09:48:34-- 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% 58.3M=0s 2016-11-14 09:48:34 (58.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112704 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.721596) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.721980) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014531 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.773230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:33.773586) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --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=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 09:48:34,661 truncating revision to first 12 chars 2016-11-14 09:48:34,661 Setting DEBUG logging. 2016-11-14 09:48:34,661 attempt 1/10 2016-11-14 09:48:34,661 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7e29173b1641?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 09:48:34,965 unpacking tar archive at: mozilla-aurora-7e29173b1641/testing/mozharness/ program finished with exit code 0 elapsedTime=0.621659 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:34.408733) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:34.409081) ========= script_repo_revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:34.409503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:34.409773) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:34.418422) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-14 09:48:34.418822) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:35 INFO - MultiFileLogger online at 20161114 09:48:35 in /builds/slave/test 09:48:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:48:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:35 INFO - {'append_to_log': False, 09:48:35 INFO - 'base_work_dir': '/builds/slave/test', 09:48:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:48:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:35 INFO - 'branch': 'Mozilla-Aurora', 09:48:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:35 INFO - 'config_files': ('talos/linux_config.py',), 09:48:35 INFO - 'default_actions': ('clobber', 09:48:35 INFO - 'read-buildbot-config', 09:48:35 INFO - 'download-and-extract', 09:48:35 INFO - 'populate-webroot', 09:48:35 INFO - 'create-virtualenv', 09:48:35 INFO - 'install', 09:48:35 INFO - 'run-tests'), 09:48:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:35 INFO - 'download_minidump_stackwalk': True, 09:48:35 INFO - 'download_symbols': 'ondemand', 09:48:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:35 INFO - 'installer_path': 'installer.exe', 09:48:35 INFO - 'log_level': 'info', 09:48:35 INFO - 'log_name': 'talos', 09:48:35 INFO - 'log_to_console': True, 09:48:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:35 INFO - 'opt_config_files': (), 09:48:35 INFO - 'pip_index': False, 09:48:35 INFO - 'sps_profile': False, 09:48:35 INFO - 'sps_profile_interval': 0, 09:48:35 INFO - 'suite': 'dromaeojs', 09:48:35 INFO - 'system_bits': '32', 09:48:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:35 INFO - 'title': 'talos-linux64-ix-004', 09:48:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:35 INFO - 'use_talos_json': True, 09:48:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:35 INFO - 'work_dir': 'build'} 09:48:35 INFO - [mozharness: 2016-11-14 17:48:35.399356Z] Running clobber step. 09:48:35 INFO - Running pre-action listener: _resource_record_pre_action 09:48:35 INFO - Running main action method: clobber 09:48:35 INFO - rmtree: /builds/slave/test/build 09:48:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:35 INFO - Running post-action listener: _resource_record_post_action 09:48:35 INFO - [mozharness: 2016-11-14 17:48:35.903163Z] Finished clobber step (success) 09:48:35 INFO - [mozharness: 2016-11-14 17:48:35.903263Z] Running read-buildbot-config step. 09:48:35 INFO - Running pre-action listener: _resource_record_pre_action 09:48:35 INFO - Running main action method: read_buildbot_config 09:48:35 INFO - Using buildbot properties: 09:48:35 INFO - { 09:48:35 INFO - "project": "", 09:48:35 INFO - "product": "firefox", 09:48:35 INFO - "script_repo_revision": "production", 09:48:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:48:35 INFO - "repository": "", 09:48:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:48:35 INFO - "buildid": "20161114043455", 09:48:35 INFO - "stage_platform": "linux64-pgo", 09:48:35 INFO - "basedir": "/builds/slave/test", 09:48:35 INFO - "nightly_build": "True", 09:48:35 INFO - "buildnumber": 591, 09:48:35 INFO - "slavename": "talos-linux64-ix-004", 09:48:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:48:35 INFO - "platform": "ubuntu64_hw", 09:48:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:48:35 INFO - "branch": "mozilla-aurora", 09:48:35 INFO - "repo_path": "releases/mozilla-aurora", 09:48:35 INFO - "revision": "7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f", 09:48:35 INFO - "pgo_build": "True", 09:48:35 INFO - "builduid": "d00525730d904e358afe39be231f63da", 09:48:35 INFO - "slavebuilddir": "test" 09:48:35 INFO - } 09:48:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 09:48:35 INFO - Running post-action listener: _resource_record_post_action 09:48:35 INFO - [mozharness: 2016-11-14 17:48:35.905750Z] Finished read-buildbot-config step (success) 09:48:35 INFO - [mozharness: 2016-11-14 17:48:35.905838Z] Running download-and-extract step. 09:48:35 INFO - Running pre-action listener: _resource_record_pre_action 09:48:35 INFO - Running main action method: download_and_extract 09:48:35 INFO - mkdir: /builds/slave/test/build/tests 09:48: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:48:35 INFO - trying https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:48:35 INFO - Downloading https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:48:36 INFO - Downloaded 1361 bytes. 09:48:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:48:36 INFO - Using the following test package requirements: 09:48:36 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:48:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:48:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'jsshell-linux-x86_64.zip'], 09:48:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:48:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:48:36 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:48:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:48:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:48:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:36 INFO - Fetch https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:48:37 INFO - Http code: 200 09:48:37 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 09:48:37 INFO - x-amz-cf-id: UCnNYjH6R-Y8po0MtPVbH9vhQ1L_Wuj8w6armdbNVB44tvQgNiGOig== 09:48:37 INFO - x-amz-version-id: .PupNpRub8iqci43oxfMUdKrecmEMiRk 09:48:37 INFO - Expected file size: 21999692 09:48:37 INFO - Obtained file size: 21999692 09:48:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:48:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:48:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:41 INFO - Fetch https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:48:42 INFO - Http code: 200 09:48:42 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:48:42 INFO - x-amz-cf-id: sxiv2bA_X8i9hVYxDFE6gUDZNUA3iQcTZE-J7hNq8J8cYIpA24Nz6g== 09:48:42 INFO - x-amz-version-id: .PupNpRub8iqci43oxfMUdKrecmEMiRk 09:48:42 INFO - Expected file size: 21999692 09:48:42 INFO - Obtained file size: 21999692 09:48:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 09:48:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:48:46 INFO - Fetch https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:48:47 INFO - Http code: 200 09:48:47 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 09:48:47 INFO - x-amz-cf-id: IgGAFeSPz0fCm5jlCRyBnElhh3JzfwgmwnwH6QFrM_KGzBQkp-913g== 09:48:47 INFO - x-amz-version-id: khC2dBqT4macoa3mo70BLo5l_yQ3AZFc 09:48:47 INFO - Expected file size: 14409038 09:48:47 INFO - Obtained file size: 14409038 09:48:47 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:48:47 INFO - trying https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:48:47 INFO - Downloading https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:48:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:48:49 INFO - Downloaded 63922512 bytes. 09:48:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:48:49 INFO - mkdir: /builds/slave/test/properties 09:48:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:49 INFO - Contents: 09:48:49 INFO - build_url:https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:48:49 INFO - Running post-action listener: _resource_record_post_action 09:48:49 INFO - Running post-action listener: set_extra_try_arguments 09:48:49 INFO - [mozharness: 2016-11-14 17:48:49.721230Z] Finished download-and-extract step (success) 09:48:49 INFO - [mozharness: 2016-11-14 17:48:49.721456Z] Running populate-webroot step. 09:48:49 INFO - Running pre-action listener: _resource_record_pre_action 09:48:49 INFO - Running main action method: populate_webroot 09:48:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:49 INFO - '--tppagecycles', 09:48:49 INFO - '7'], 09:48:49 INFO - 'tests': ['tsvgm']}}, 09:48:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['damp', 'tps']}, 09:48:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'tests': ['damp', 'tps']}, 09:48:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['dromaeo_dom']}, 09:48:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['basic_compositor_video']}, 09:48:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['a11yr', 09:48:49 INFO - 'ts_paint', 09:48:49 INFO - 'tpaint', 09:48:49 INFO - 'sessionrestore', 09:48:49 INFO - 'sessionrestore_no_auto_restore', 09:48:49 INFO - 'tabpaint']}, 09:48:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['tsvgx', 09:48:49 INFO - 'tsvgr_opacity', 09:48:49 INFO - 'tart', 09:48:49 INFO - 'tscrollx', 09:48:49 INFO - 'cart']}, 09:48:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:49 INFO - 'tsvgr_opacity', 09:48:49 INFO - 'tart', 09:48:49 INFO - 'tscrollx', 09:48:49 INFO - 'cart']}, 09:48:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'talos_options': ['--disable-e10s'], 09:48:49 INFO - 'tests': ['tp5o']}, 09:48:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'tests': ['tp5o']}, 09:48:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'talos_options': ['--disable-e10s', 09:48:49 INFO - '--xperf_path', 09:48:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:49 INFO - 'tests': ['tp5n']}, 09:48:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:49 INFO - 'talos_options': ['--xperf_path', 09:48:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:49 INFO - 'tests': ['tp5n']}}, 09:48:49 INFO - 'talos.zip': {'path': '', 09:48:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:49 INFO - Running post-action listener: _resource_record_post_action 09:48:49 INFO - [mozharness: 2016-11-14 17:48:49.744231Z] Finished populate-webroot step (success) 09:48:49 INFO - [mozharness: 2016-11-14 17:48:49.744329Z] Running create-virtualenv step. 09:48:49 INFO - Running pre-action listener: _resource_record_pre_action 09:48:49 INFO - Running main action method: create_virtualenv 09:48:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:49 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:48:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:49 INFO - Using real prefix '/usr' 09:48:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:52 INFO - Installing pip.................done. 09:48:52 INFO - Return code: 0 09:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:52 INFO - Reading from file tmpfile_stdout 09:48:52 INFO - Output received: 09:48:52 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:48:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:52 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:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:52 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:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:52 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:48:52 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:48:52 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:48:52 INFO - Using env: {'DISPLAY': ':0', 09:48:52 INFO - 'HOME': '/home/cltbld', 09:48:52 INFO - 'LANG': 'en_US.UTF-8', 09:48:52 INFO - 'LANGUAGE': 'en_US:en', 09:48:52 INFO - 'LOGNAME': 'cltbld', 09:48:52 INFO - 'MAIL': '/var/mail/cltbld', 09:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:52 INFO - 'MOZ_NO_REMOTE': '1', 09:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:52 INFO - 'NO_EM_RESTART': '1', 09:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:52 INFO - 'PWD': '/builds/slave/test', 09:48:52 INFO - 'SHELL': '/bin/bash', 09:48:52 INFO - 'SHLVL': '1', 09:48:52 INFO - 'TERM': 'linux', 09:48:52 INFO - 'TMOUT': '86400', 09:48:52 INFO - 'USER': 'cltbld', 09:48:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:52 INFO - '_': '/tools/buildbot/bin/python'} 09:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:52 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:48:52 INFO - Cleaning up... 09:48:52 INFO - Return code: 0 09:48:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:52 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:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:52 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:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:52 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:48:52 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:48:52 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:48:52 INFO - Using env: {'DISPLAY': ':0', 09:48:52 INFO - 'HOME': '/home/cltbld', 09:48:52 INFO - 'LANG': 'en_US.UTF-8', 09:48:52 INFO - 'LANGUAGE': 'en_US:en', 09:48:52 INFO - 'LOGNAME': 'cltbld', 09:48:52 INFO - 'MAIL': '/var/mail/cltbld', 09:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:52 INFO - 'MOZ_NO_REMOTE': '1', 09:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:52 INFO - 'NO_EM_RESTART': '1', 09:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:52 INFO - 'PWD': '/builds/slave/test', 09:48:52 INFO - 'SHELL': '/bin/bash', 09:48:52 INFO - 'SHLVL': '1', 09:48:52 INFO - 'TERM': 'linux', 09:48:52 INFO - 'TMOUT': '86400', 09:48:52 INFO - 'USER': 'cltbld', 09:48:52 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:52 INFO - '_': '/tools/buildbot/bin/python'} 09:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:52 INFO - Downloading/unpacking psutil>=3.1.1 09:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:55 INFO - Installing collected packages: psutil 09:48:55 INFO - Running setup.py install for psutil 09:48:55 INFO - building 'psutil._psutil_linux' extension 09:48:55 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:48:55 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:48:55 INFO - building 'psutil._psutil_posix' extension 09:48:55 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:48:55 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:48:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:55 INFO - Successfully installed psutil 09:48:55 INFO - Cleaning up... 09:48:55 INFO - Return code: 0 09:48:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:48:55 INFO - Using env: {'DISPLAY': ':0', 09:48:55 INFO - 'HOME': '/home/cltbld', 09:48:55 INFO - 'LANG': 'en_US.UTF-8', 09:48:55 INFO - 'LANGUAGE': 'en_US:en', 09:48:55 INFO - 'LOGNAME': 'cltbld', 09:48:55 INFO - 'MAIL': '/var/mail/cltbld', 09:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:55 INFO - 'MOZ_NO_REMOTE': '1', 09:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:55 INFO - 'NO_EM_RESTART': '1', 09:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:55 INFO - 'PWD': '/builds/slave/test', 09:48:55 INFO - 'SHELL': '/bin/bash', 09:48:55 INFO - 'SHLVL': '1', 09:48:55 INFO - 'TERM': 'linux', 09:48:55 INFO - 'TMOUT': '86400', 09:48:55 INFO - 'USER': 'cltbld', 09:48:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:55 INFO - '_': '/tools/buildbot/bin/python'} 09:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:55 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:48:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:57 INFO - Installing collected packages: mozsystemmonitor 09:48:57 INFO - Running setup.py install for mozsystemmonitor 09:48:57 INFO - Successfully installed mozsystemmonitor 09:48:57 INFO - Cleaning up... 09:48:57 INFO - Return code: 0 09:48:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48: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:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:48: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:48: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:48:57 INFO - Using env: {'DISPLAY': ':0', 09:48:57 INFO - 'HOME': '/home/cltbld', 09:48:57 INFO - 'LANG': 'en_US.UTF-8', 09:48:57 INFO - 'LANGUAGE': 'en_US:en', 09:48:57 INFO - 'LOGNAME': 'cltbld', 09:48:57 INFO - 'MAIL': '/var/mail/cltbld', 09:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:57 INFO - 'MOZ_NO_REMOTE': '1', 09:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:57 INFO - 'NO_EM_RESTART': '1', 09:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:57 INFO - 'PWD': '/builds/slave/test', 09:48:57 INFO - 'SHELL': '/bin/bash', 09:48:57 INFO - 'SHLVL': '1', 09:48:57 INFO - 'TERM': 'linux', 09:48:57 INFO - 'TMOUT': '86400', 09:48:57 INFO - 'USER': 'cltbld', 09:48:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:57 INFO - '_': '/tools/buildbot/bin/python'} 09:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:58 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:58 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:48:58 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:48:58 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:48:58 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:48:58 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:48:58 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:49:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:49:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:49: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:49: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:49: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:49: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:49: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:49: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:49:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:49:00 INFO - warning: no files found matching '*.txt' 09:49:00 INFO - no previously-included directories found matching 'build' 09:49:00 INFO - no previously-included directories found matching 'dist' 09:49:00 INFO - no previously-included directories found matching '.git*' 09:49:00 INFO - Installing collected packages: jsonschema, functools32 09:49:00 INFO - Running setup.py install for functools32 09:49:00 INFO - warning: no files found matching '*.txt' 09:49:00 INFO - no previously-included directories found matching 'build' 09:49:00 INFO - no previously-included directories found matching 'dist' 09:49:00 INFO - no previously-included directories found matching '.git*' 09:49:00 INFO - Successfully installed jsonschema functools32 09:49:00 INFO - Cleaning up... 09:49:00 INFO - Return code: 0 09:49:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:00 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:00 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:49:00 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:49:00 INFO - Using env: {'DISPLAY': ':0', 09:49:00 INFO - 'HOME': '/home/cltbld', 09:49:00 INFO - 'LANG': 'en_US.UTF-8', 09:49:00 INFO - 'LANGUAGE': 'en_US:en', 09:49:00 INFO - 'LOGNAME': 'cltbld', 09:49:00 INFO - 'MAIL': '/var/mail/cltbld', 09:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:00 INFO - 'MOZ_NO_REMOTE': '1', 09:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:00 INFO - 'NO_EM_RESTART': '1', 09:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:00 INFO - 'PWD': '/builds/slave/test', 09:49:00 INFO - 'SHELL': '/bin/bash', 09:49:00 INFO - 'SHLVL': '1', 09:49:00 INFO - 'TERM': 'linux', 09:49:00 INFO - 'TMOUT': '86400', 09:49:00 INFO - 'USER': 'cltbld', 09:49:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:00 INFO - '_': '/tools/buildbot/bin/python'} 09:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:49:00 INFO - Cleaning up... 09:49:00 INFO - Return code: 0 09:49:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:00 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:00 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:49:00 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:49:00 INFO - Using env: {'DISPLAY': ':0', 09:49:00 INFO - 'HOME': '/home/cltbld', 09:49:00 INFO - 'LANG': 'en_US.UTF-8', 09:49:00 INFO - 'LANGUAGE': 'en_US:en', 09:49:00 INFO - 'LOGNAME': 'cltbld', 09:49:00 INFO - 'MAIL': '/var/mail/cltbld', 09:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:00 INFO - 'MOZ_NO_REMOTE': '1', 09:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:00 INFO - 'NO_EM_RESTART': '1', 09:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:00 INFO - 'PWD': '/builds/slave/test', 09:49:00 INFO - 'SHELL': '/bin/bash', 09:49:00 INFO - 'SHLVL': '1', 09:49:00 INFO - 'TERM': 'linux', 09:49:00 INFO - 'TMOUT': '86400', 09:49:00 INFO - 'USER': 'cltbld', 09:49:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:00 INFO - '_': '/tools/buildbot/bin/python'} 09:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:49: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:49: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:49: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:49: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:49: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:49: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:49:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49: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:49: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:49: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:49: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:49: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:49: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:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:03 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:49:03 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:49:03 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:49:03 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:49:03 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:49:03 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:49:03 INFO - Downloading docopt-0.6.1.tar.gz 09:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:03 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:03 INFO - Running setup.py install for blobuploader 09:49:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:03 INFO - Running setup.py install for requests 09:49:04 INFO - Running setup.py install for docopt 09:49:04 INFO - Successfully installed blobuploader requests docopt 09:49:04 INFO - Cleaning up... 09:49:04 INFO - Return code: 0 09:49:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:04 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:49:04 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:49:04 INFO - Using env: {'DISPLAY': ':0', 09:49:04 INFO - 'HOME': '/home/cltbld', 09:49:04 INFO - 'LANG': 'en_US.UTF-8', 09:49:04 INFO - 'LANGUAGE': 'en_US:en', 09:49:04 INFO - 'LOGNAME': 'cltbld', 09:49:04 INFO - 'MAIL': '/var/mail/cltbld', 09:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:04 INFO - 'MOZ_NO_REMOTE': '1', 09:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:04 INFO - 'NO_EM_RESTART': '1', 09:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:04 INFO - 'PWD': '/builds/slave/test', 09:49:04 INFO - 'SHELL': '/bin/bash', 09:49:04 INFO - 'SHLVL': '1', 09:49:04 INFO - 'TERM': 'linux', 09:49:04 INFO - 'TMOUT': '86400', 09:49:04 INFO - 'USER': 'cltbld', 09:49:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:04 INFO - '_': '/tools/buildbot/bin/python'} 09:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:04 INFO - Running setup.py (path:/tmp/pip-G_LA9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:04 INFO - Running setup.py (path:/tmp/pip-iYozyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:04 INFO - Running setup.py (path:/tmp/pip-G1IX8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:04 INFO - Running setup.py (path:/tmp/pip-RW4Ruv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:04 INFO - Running setup.py (path:/tmp/pip-cA2lBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:04 INFO - Running setup.py (path:/tmp/pip-xe0MNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:04 INFO - Running setup.py (path:/tmp/pip-PB75ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 INFO - Running setup.py (path:/tmp/pip-9bGejW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:05 INFO - Running setup.py (path:/tmp/pip-QZCoNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Running setup.py (path:/tmp/pip-_rLIOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 INFO - Running setup.py (path:/tmp/pip-fXI5Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:05 INFO - Running setup.py (path:/tmp/pip-6NLqKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:05 INFO - Running setup.py (path:/tmp/pip-cPx3U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:05 INFO - Running setup.py (path:/tmp/pip-wPgk7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:05 INFO - Running setup.py (path:/tmp/pip-T5u91R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:05 INFO - Running setup.py (path:/tmp/pip-S_RctG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:05 INFO - Running setup.py (path:/tmp/pip-Mu31DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:05 INFO - Running setup.py install for manifestparser 09:49:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:05 INFO - Running setup.py install for mozcrash 09:49:05 INFO - Running setup.py install for mozdebug 09:49:06 INFO - Running setup.py install for mozdevice 09:49:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for mozfile 09:49:06 INFO - Running setup.py install for mozhttpd 09:49:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for mozinfo 09:49:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for mozInstall 09:49:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for mozleak 09:49:06 INFO - Running setup.py install for mozlog 09:49:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for moznetwork 09:49:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:06 INFO - Running setup.py install for mozprocess 09:49:06 INFO - Running setup.py install for mozprofile 09:49:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:07 INFO - Running setup.py install for mozrunner 09:49:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:07 INFO - Running setup.py install for mozscreenshot 09:49:07 INFO - Running setup.py install for moztest 09:49:07 INFO - Running setup.py install for mozversion 09:49:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:07 INFO - Cleaning up... 09:49:07 INFO - Return code: 0 09:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:07 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:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:07 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:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:07 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:07 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:49:07 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:49:07 INFO - Using env: {'DISPLAY': ':0', 09:49:07 INFO - 'HOME': '/home/cltbld', 09:49:07 INFO - 'LANG': 'en_US.UTF-8', 09:49:07 INFO - 'LANGUAGE': 'en_US:en', 09:49:07 INFO - 'LOGNAME': 'cltbld', 09:49:07 INFO - 'MAIL': '/var/mail/cltbld', 09:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:07 INFO - 'MOZ_NO_REMOTE': '1', 09:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:07 INFO - 'NO_EM_RESTART': '1', 09:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:07 INFO - 'PWD': '/builds/slave/test', 09:49:07 INFO - 'SHELL': '/bin/bash', 09:49:07 INFO - 'SHLVL': '1', 09:49:07 INFO - 'TERM': 'linux', 09:49:07 INFO - 'TMOUT': '86400', 09:49:07 INFO - 'USER': 'cltbld', 09:49:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:07 INFO - '_': '/tools/buildbot/bin/python'} 09:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:07 INFO - Running setup.py (path:/tmp/pip-mivBFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49: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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:07 INFO - Running setup.py (path:/tmp/pip-9OzGfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49: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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:07 INFO - Running setup.py (path:/tmp/pip-dyF68L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49: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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:07 INFO - Running setup.py (path:/tmp/pip-MfF_ur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:08 INFO - Running setup.py (path:/tmp/pip-eIb0jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:08 INFO - Running setup.py (path:/tmp/pip-noVbcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:08 INFO - Running setup.py (path:/tmp/pip-xXu_J0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:08 INFO - Running setup.py (path:/tmp/pip-0298Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:08 INFO - Running setup.py (path:/tmp/pip-3aCz1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:08 INFO - Running setup.py (path:/tmp/pip-fa5S44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:08 INFO - Running setup.py (path:/tmp/pip-vmxWrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:08 INFO - Running setup.py (path:/tmp/pip-UzXMSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:08 INFO - Running setup.py (path:/tmp/pip-nvfBB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:08 INFO - Running setup.py (path:/tmp/pip-sGIRAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49: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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:08 INFO - Running setup.py (path:/tmp/pip-4wrB1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49: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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:08 INFO - Running setup.py (path:/tmp/pip-XLmj91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:08 INFO - Running setup.py (path:/tmp/pip-S47wTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49: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:49: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:49: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:49: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:49: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:49: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:49:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:10 INFO - Downloading blessings-1.6.tar.gz 09:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:10 INFO - Installing collected packages: blessings 09:49:10 INFO - Running setup.py install for blessings 09:49:11 INFO - Successfully installed blessings 09:49:11 INFO - Cleaning up... 09:49:11 INFO - Return code: 0 09:49:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:11 INFO - Reading from file tmpfile_stdout 09:49:11 INFO - Current package versions: 09:49:11 INFO - argparse == 1.2.1 09:49:11 INFO - blessings == 1.6 09:49:11 INFO - blobuploader == 1.2.4 09:49:11 INFO - docopt == 0.6.1 09:49:11 INFO - functools32 == 3.2.3-2 09:49:11 INFO - jsonschema == 2.5.1 09:49:11 INFO - manifestparser == 1.1 09:49:11 INFO - mozInstall == 1.12 09:49:11 INFO - mozcrash == 1.0 09:49:11 INFO - mozdebug == 0.1 09:49:11 INFO - mozdevice == 0.48 09:49:11 INFO - mozfile == 1.2 09:49:11 INFO - mozhttpd == 0.7 09:49:11 INFO - mozinfo == 0.9 09:49:11 INFO - mozleak == 0.1 09:49:11 INFO - mozlog == 3.4 09:49:11 INFO - moznetwork == 0.27 09:49:11 INFO - mozprocess == 0.23 09:49:11 INFO - mozprofile == 0.28 09:49:11 INFO - mozrunner == 6.13 09:49:11 INFO - mozscreenshot == 0.1 09:49:11 INFO - mozsystemmonitor == 0.3 09:49:11 INFO - moztest == 0.7 09:49:11 INFO - mozversion == 1.4 09:49:11 INFO - psutil == 3.1.1 09:49:11 INFO - requests == 1.2.3 09:49:11 INFO - wsgiref == 0.1.2 09:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:11 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:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:11 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:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:11 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:11 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:49:11 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:49:11 INFO - Using env: {'DISPLAY': ':0', 09:49:11 INFO - 'HOME': '/home/cltbld', 09:49:11 INFO - 'LANG': 'en_US.UTF-8', 09:49:11 INFO - 'LANGUAGE': 'en_US:en', 09:49:11 INFO - 'LOGNAME': 'cltbld', 09:49:11 INFO - 'MAIL': '/var/mail/cltbld', 09:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:11 INFO - 'MOZ_NO_REMOTE': '1', 09:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:11 INFO - 'NO_EM_RESTART': '1', 09:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:11 INFO - 'PWD': '/builds/slave/test', 09:49:11 INFO - 'SHELL': '/bin/bash', 09:49:11 INFO - 'SHLVL': '1', 09:49:11 INFO - 'TERM': 'linux', 09:49:11 INFO - 'TMOUT': '86400', 09:49:11 INFO - 'USER': 'cltbld', 09:49:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:11 INFO - '_': '/tools/buildbot/bin/python'} 09:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 INFO - Cleaning up... 09:49:11 INFO - Return code: 0 09:49:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:11 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:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:11 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:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:11 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 0x7f8f1b258f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a3fca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', '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:49:11 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:49:11 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:49:11 INFO - Using env: {'DISPLAY': ':0', 09:49:11 INFO - 'HOME': '/home/cltbld', 09:49:11 INFO - 'LANG': 'en_US.UTF-8', 09:49:11 INFO - 'LANGUAGE': 'en_US:en', 09:49:11 INFO - 'LOGNAME': 'cltbld', 09:49:11 INFO - 'MAIL': '/var/mail/cltbld', 09:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:11 INFO - 'MOZ_NO_REMOTE': '1', 09:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:11 INFO - 'NO_EM_RESTART': '1', 09:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:11 INFO - 'PWD': '/builds/slave/test', 09:49:11 INFO - 'SHELL': '/bin/bash', 09:49:11 INFO - 'SHLVL': '1', 09:49:11 INFO - 'TERM': 'linux', 09:49:11 INFO - 'TMOUT': '86400', 09:49:11 INFO - 'USER': 'cltbld', 09:49:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:11 INFO - '_': '/tools/buildbot/bin/python'} 09:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:11 INFO - Cleaning up... 09:49:11 INFO - Return code: 0 09:49:11 INFO - Running post-action listener: _resource_record_post_action 09:49:11 INFO - Running post-action listener: _start_resource_monitoring 09:49:11 INFO - Starting resource monitoring. 09:49:11 INFO - [mozharness: 2016-11-14 17:49:11.876241Z] Finished create-virtualenv step (success) 09:49:11 INFO - [mozharness: 2016-11-14 17:49:11.876743Z] Running install step. 09:49:11 INFO - Running pre-action listener: _resource_record_pre_action 09:49:11 INFO - Running main action method: install 09:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:12 INFO - Reading from file tmpfile_stdout 09:49:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:12 INFO - Reading from file tmpfile_stdout 09:49:12 INFO - Output received: 09:49:12 INFO - Usage: mozinstall [options] installer 09:49:12 INFO - Options: 09:49:12 INFO - -h, --help show this help message and exit 09:49:12 INFO - -d DEST, --destination=DEST 09:49:12 INFO - Directory to install application into. [default: 09:49:12 INFO - "/builds/slave/test"] 09:49:12 INFO - --app=APP Application being installed. [default: firefox] 09:49:12 INFO - mkdir: /builds/slave/test/build/application 09:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:49:28 INFO - Reading from file tmpfile_stdout 09:49:28 INFO - Output received: 09:49:28 INFO - /builds/slave/test/build/application/firefox/firefox 09:49:28 INFO - Running post-action listener: _resource_record_post_action 09:49:28 INFO - [mozharness: 2016-11-14 17:49:28.070130Z] Finished install step (success) 09:49:28 INFO - [mozharness: 2016-11-14 17:49:28.070371Z] Running run-tests step. 09:49:28 INFO - Running pre-action listener: _resource_record_pre_action 09:49:28 INFO - Running main action method: run_tests 09:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:28 INFO - Python 2.7.3 09:49:28 INFO - Return code: 0 09:49:28 INFO - grabbing minidump binary from tooltool 09:49:28 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:49:28 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 0x2a3cf60>, '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 0x2a3dd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3e1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:28 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:49:28 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:49:28 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:49:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:28 INFO - Return code: 0 09:49:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:49:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:49:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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:49:28 INFO - Using env: {'DISPLAY': ':0', 09:49:28 INFO - 'HOME': '/home/cltbld', 09:49:28 INFO - 'LANG': 'en_US.UTF-8', 09:49:28 INFO - 'LANGUAGE': 'en_US:en', 09:49:28 INFO - 'LOGNAME': 'cltbld', 09:49:28 INFO - 'MAIL': '/var/mail/cltbld', 09:49:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:28 INFO - 'MOZ_NO_REMOTE': '1', 09:49:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:28 INFO - 'NO_EM_RESTART': '1', 09:49:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:28 INFO - 'PWD': '/builds/slave/test', 09:49:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:28 INFO - 'SHELL': '/bin/bash', 09:49:28 INFO - 'SHLVL': '1', 09:49:28 INFO - 'TERM': 'linux', 09:49:28 INFO - 'TMOUT': '86400', 09:49:28 INFO - 'USER': 'cltbld', 09:49:28 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260', 09:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:28 INFO - '_': '/tools/buildbot/bin/python'} 09:49:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:49:28 INFO - mozversion application_buildid: 20161114043455 09:49:28 INFO - mozversion application_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 09:49:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:49:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:28 INFO - mozversion application_name: Firefox 09:49:28 INFO - mozversion application_remotingname: firefox-dev 09:49:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:28 INFO - mozversion application_vendor: Mozilla 09:49:28 INFO - mozversion application_version: 52.0a2 09:49:28 INFO - mozversion platform_buildid: 20161114043455 09:49:28 INFO - mozversion platform_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 09:49:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:28 INFO - mozversion platform_version: 52.0a2 09:49:28 INFO - using testdate: 1479145768 09:49:28 INFO - actual date: 1479145768 09:49:28 INFO - starting webserver on 'localhost:33416' 09:49:28 INFO - SUITE-START | Running 2 tests 09:49:28 INFO - TEST-START | dromaeo_css 09:49:28 INFO - operating with platform_type : linux_ 09:49:28 INFO - Initialising browser for dromaeo_css test... 09:49:28 INFO - TEST-INFO | started process 31361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp863ciN/profile http://localhost:33416/getInfo.html) 09:49:31 ERROR - PROCESS | 31361 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:49:32 INFO - PROCESS | 31361 | __metrics Screen width/height:1600/1200 09:49:32 INFO - PROCESS | 31361 | colorDepth:24 09:49:32 INFO - PROCESS | 31361 | Browser inner width/height: 1024/697 09:49:32 INFO - PROCESS | 31361 | __metrics 09:49:33 INFO - PROCESS | 31361 | 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:49:33 INFO - PROCESS | 31361 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:33 INFO - PROCESS | 31361 | console.error: PushService: 09:49:33 INFO - PROCESS | 31361 | stateChangeProcessEnqueue: Error transitioning state 09:49:33 INFO - PROCESS | 31361 | UnknownError 09:49:34 INFO - TEST-INFO | 31361: exit 0 09:49:34 INFO - Browser initialized. 09:49:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:34 INFO - TEST-INFO | started process 31452 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp863ciN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:46 INFO - PROCESS | 31452 | RSS: Main: 148570112 09:49:46 INFO - PROCESS | 31452 | 09:50:37 INFO - PROCESS | 31452 | 1479145837448 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:52:47 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33416/tests/dromaeo/cssquery-ext.html) 09:52:48 INFO - PROCESS | 31452 | RSS: Main: 242421760 09:52:48 INFO - PROCESS | 31452 | 09:55:51 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-ext.html (next: http://localhost:33416/tests/dromaeo/cssquery-jquery.html) 09:55:51 INFO - PROCESS | 31452 | RSS: Main: 235589632 09:55:51 INFO - PROCESS | 31452 | 09:58:53 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33416/tests/dromaeo/cssquery-mootools.html) 09:58:54 INFO - PROCESS | 31452 | RSS: Main: 175534080 09:58:54 INFO - PROCESS | 31452 | 10:01:56 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33416/tests/dromaeo/cssquery-prototype.html) 10:01:56 INFO - PROCESS | 31452 | RSS: Main: 227401728 10:01:56 INFO - PROCESS | 31452 | 10:04:59 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33416/tests/dromaeo/cssquery-yui.html) 10:05:00 INFO - PROCESS | 31452 | RSS: Main: 176017408 10:05:00 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | Cycle 1(1): loaded http://localhost:33416/tests/dromaeo/cssquery-yui.html (next: http://localhost:33416/tests/dromaeo/cssquery-dojo.html) 10:08:02 INFO - PROCESS | 31452 | RSS: Main: 266027008 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | __start_tp_report 10:08:02 INFO - PROCESS | 31452 | _x_x_mozilla_page_load 10:08:02 INFO - PROCESS | 31452 | _x_x_mozilla_page_load_details 10:08:02 INFO - PROCESS | 31452 | |i|pagename|runs| 10:08:02 INFO - PROCESS | 31452 | |0;dojo.html;3101.0851798719377;3145.24514116612;3146.9527957080663;3161.8735250589975;3164.0349693343537;5382.811601593945;5434.592405569589;5437.32033495813;5469.78120875165;5477.877035207783;5428.9185662214995;5446.564274858012;5466.9179962300595;5471.699056551888;5485.396606373296;4931.482194369584;4980.4272508661425;4989.027139707759;5031.647784655075;5033.320501732256;4850;4991.376077990251;5050.267711181877;5051.848444546669;5080.542751152396;4838.806447742086;4902.044101400228;4906.558409743122;4913.778879950393;4945.431275403321;4944.258361245806;4950.381202349707;4983.302337672729;5044.747762611892;5067.949320506821;4968.279599458095;4983.551480366748;5000.699958002495;5005.124103281911;5030.597552195815;5179.974100129513;5292.073887069786;5299.761510732026;5343.225232341328;5396.946030539723;5716.056850619642;5754.769809207627;5786.161006654053;5791.305043394819;5802.5938184327115;5811.767529298823;5812.244408226903;5826.300843898759;5842.123681447817;5859.707014649293;5296.655717378397;5333.893322133536;5382.138857782736;5492.423295553909;5511.724413779295;5943.881122377528;5962.791302304398;5965.194698715704;5967.880642387215;5989.610675306091;5714.771409143671;5736;5738.540916726652;5738.913916291259;5748.655080695172;5630.802921897796;5694;5711.400302968212;5751.13732940054;5764.682942438122;5778.8266352009505;5806.1000544915605;5810.477057064884;5820.359760426434;5827.5920685552455;5723.856903577361;5780.046292361713;5797.855053623609;5808.767649294065;5810.360860305363;4396.516383197845;4416;4478.596926276586;4527.569880861313;4530.864074077783;4029.6373326400767;4047.7571345719352;4061.898452538651;4063.918721625551;4083.060895993938;5561.555075593943;5606.159076138611;5608.915866262009;5617.747201375948;5621.4378562144275;5657.066584013674;5724.198612194296;5741.224934633855;5862.501687356623;5872.442117998783;5506.669599824023;5574.08027675429;5580.469855363734;5595;5607.046802043662;4963.60291176705;5001.19980803077;5008.574271186917;5011.924821127686;5016.272640467112;5868.295804503487;5876.059830427283;6056.3338032816355;6060.818175454831;6072.726727297282;5542.168674698827;5759.280089988752;5815;5852.678102704393;5867.20792692998;6104.69476526163;6109.053096770008;6109.2974307955155;6111.8777624446375;6115.266168059861;5525.4474552544425;5798.623089499169;5799.710014499343;5801.361850196559;5808.593398462067;5637.57718171147;5804.854878627899;5836.649801011783;5846.444587764156;5968.224130863134;5796.072628379439;5809.854753631024;5812.593118481665;5842.883142337215;5846.912296315473;5661.547076233809;5792.5655575832825;5793.594448388572;5795.507381872588;5797.275340582427;5477.397486276427;5528.778848846001;5647.3505546861;5712.400197979152;5712.514436272963;5791.768329266951;5791.971040144772;5793.478586927196;5800.535957123504;5806.129080637938;5492.5331346835965;5550.389457159628;5649.491545760901;5657.377688454185;5663.03728366187 10:08:02 INFO - PROCESS | 31452 | |1;ext.html;570.7260514952823;617.7930393318238;631.1227393922447;653.5131327161265;657.1424291299854;33558.83220583897;34181.65818341819;37007.62992370079;44224;45392.09215815682;46042;46313.07373852521;46405.76797116018;46408;46461;8797.7800554986;8960.372773905829;8966.14821591951;8969.506677132755;8970.41692289999;8739.650413983432;8828.8675669865;8830.293576513895;8834.911650883505;8843.292536597057;8800.075992020842;8840.248578870804;8840.778980525507;8854.335924805633;8854.335924805633;8810;8853.557322133867;8864.069272726336;8865.024847266795;8868.290536757077;8704.434211776213;8778.868316975251;8783.253423459013;8784.472931624123;8792.780180495474;42649.1470170598;43032.3545146823;43077.784611077055;43088.56911430877;43169.136617267475;3880.864169754045;3910.022494376406;3925.646691797752;4179.226843034048;4344.348347747863;32440.837795811105;32510.18724531882;32807.83596082028;32938.341233175204;34503;4121.814518346651;4254.0879750182;4258.87223383299;4263.275243208662;4316.330968699856;350353;351402;351836;352540;352983.2350838255;54192.72903635457;54307.45692543024;54327.45672543224;54382.45617543853;54432;39905.40141897816;40419.19161616751;40622.79688601538;42419.78790106092;42427.575724242364;19155.329563465213;19384.2246310146;19397.806021939883;19399.515012124808;19403.708944365844;12698.80951785724;12700;12704.936475317565;12706;12709.809352859715;20321.79678203199;20377.79622203759;20477.590448190953;20485.59028819415;20520.947905209658;7166.605836678928;7179.425645948311;7190.352868241884;7268.963655181796;7413.332800047914;981.9852702209473;1065.616378103882;1069.8983596558314;1081.469720345702;1125.2404626876826;32930.50604240938;33003.83498082494;33319.33361332808;33360.49959250613;33365.332693346;1082.1288862465633;1090.8636420447444;1108.3903852881044;1125.6229163230246;1171.9276861671476;16484.258208380652;17214.42405606687;17619.119044047748;17874.642507149783;17998.640027199646;411664;619308;627198;627978;628369;60935;61556;61667.38332616706;61680.38319616836;61712.69143654343;1306.372940988312;1365.262758110629;1373.176094343406;1413.4770135050098;1425.6079578115894;31813.68186318107;35864.64135358613;35956.4606530897;35978.64021359753;36014.819925901254;58483.4151658478;59022.40977590169;59078;59088;59255.70372148284;477985;482574;482927;484049;486649;30363.39273214479;30969.8451507741;31422.528662070545;33076.66923330736;33206.83396583002;50210.497895020584;50415.24377134419;50731;50874.49125508698;50969.23546146885;41479.92866616646;42932.35601466043;43015.784921075196;43711;44425.333619996374;42989.57010429856;43024.84875756262;43903.78048109739;44348;45111;37684.811575943044;38470.615293846706;38769;38890.416643750934;43771.12457750891;35011.54919155635;38843;38949.6105038946;39134.217315654096;39358.60641393549;28613.28466788347;28725.42549149047;28783.568246476738;29100.854495728232;29150.7084929148 10:08:02 INFO - PROCESS | 31452 | |2;jquery.html;433.8134602121087;433.84164779855354;433.90887913538154;441.234458214997;447.88131145246496;18651.440456786302;18975.240990360402;20110.396688099343;20310.18759249632;20418.69371959422;16041.278142483587;19474.41576752696;20114;20728.48178795531;20731.689024664644;5130.53825155735;5391.460853914617;5393.298871146765;5399.62202645815;5402.91895621566;5333.759980800874;5337.866553336178;5413.025655382049;5415.079436495806;5904.88190236195;5870.471657550841;5892.175095486614;5917.082852157922;5919.141724449953;5920.141579470974;5477.233187353734;5519.31008623922;5529.944700552983;5903.35063143054;5915.319738230116;5358.437364076754;5361.544268737162;5366;5366.060939335601;5375.838724838261;15187.848121518864;15659.13874736889;23510.177143800054;23580.820958951983;24408.38979025521;2461.9753802461923;2607.178738697304;2783.805133640646;2800.635917330734;3013.276813564729;10432.530536125892;10995.450227488594;11280.830787538194;11424.485898134604;11485.655430337103;1942.3978566644385;1966.1349006437265;2007.9498012549657;2011.3161525081396;2052.6613108837173;71070;85661.57169214114;91132.54433727855;92575.07424925732;93865;11711.941440292914;11862.762744745056;11906.404679765978;12186.878131218575;12322.630321090382;17197.91401043012;17282.481525554253;17361.045142517283;17374.13129343528;17388.565285867953;8345.874811877828;8539.480853148336;8680.30557555394;8971.37200395979;9011.63953441855;8172.305354044973;8644.524551149747;8657.22085012352;8897;8941.687040953535;5072.239164125337;5158.896822063538;5173.534381905646;5192.402873669501;5292.259083728281;1992.5417154054649;2043.846711496614;2079.677649964258;2133.4026472587702;2134.1996751218294;950.1116456113556;984.6307634637011;994.7363948553639;1043.6295115234134;1064.3347907557777;19013.714794278098;19932.70100948487;20163.39509814708;20177.701061117885;20469.692954605693;982.0081717465412;990.346371394876;1032.1226957086567;1033.3180101133216;1046.9057784799404;10391.636292729718;11206.663800086011;11292.435378231055;11308.09535237179;11472.4837382318;461249;464319;465756;466403;470840;81058;83821.16178838255;97206.51396743112;99484;101284;952.0717300631967;974.7952929884661;982.7150126463389;1011.3325205364424;1020.4897551224387;17622.735658965383;17862.910685446488;18130.637387251918;18148.27406903762;18425.81574184241;43508.782456087516;43785.56214437942;43940.560594393646;44376;45582;60831.69584152051;61168.38831611627;61558.38441615526;61994.69002654958;63503.6824815873;5779.075347944308;7523.360514356122;7878.724244651639;8849.867251991354;9342.205912497242;12395.194312369667;13002.869971300166;13424.865751342362;13770.311484425538;13974.65063373383;11592.304461732323;11866.228695134789;12054.75890482168;12328.568500102454;12605.243685378906;11515.8272625909;11548.42257887119;11791.233569817934;12009.279443233434;12408.441620127296;11967.461464234304;12059.819102713642;12067.215630984309;12081.275123492618;12111;8926.86609700868;8984.685536006209;9037;9232.584533696134;9346.15884570392;11615.6515304541;12111.273323600612;12146.331951742739;12179.939100304442;12622.495100195889 10:08:02 INFO - PROCESS | 31452 | |3;mootools.html;1507.9246037698113;1533.6702608939077;1669.8163202047772;1790.606066665334;1791.9641607167864;8546.40175187737;9126.041765614615;9134.908650913489;9136.817263654722;9150.359474836756;7580.6967721291085;7847.843043139133;8569.95715021424;8753.212210901018;8772.254358379545;2514.5473814713346;2536.720960694322;2543.2878793937725;2543.3514453814346;2543.529447052292;2485.2668462803426;2528.5448619248527;2543;2545.350935511442;2546.1088618983395;2349.0486353026968;2379.95240095198;2503.4993001399703;2506.8119891008155;2509.987450062756;2433.9634905476432;2437.463757973243;2439.8048156147465;2446.559619268531;2463.5935070713313;2414.4929564791414;2429.307647320505;2448.6939132608422;2450.1669432392896;2471.2586224132688;8980.14688604588;9010.819783604291;9051.778009968702;9062.77343066422;9063.909360906373;5475;5614.522765564931;5625.212470254168;5625.774969001235;5627.099664053731;3543.698785603227;3634.745567810254;3715.8327875245677;3728.5898551159353;3736.40217565191;1176.8352430659716;1193.8209268609692;1228.582282024116;1256.447163248168;1281.9166754161042;12352.320622365767;12358.75282494345;12428.502859885593;12441.129120961536;12464.065195110403;4655.96592178493;4862.635302352338;4881.4630390657;4940.654154209243;5005.399352077774;10431.5827366906;10459;10478.214133939984;10481.056704896597;10488.580456781643;5594.328680558359;5606.159076138611;5607.971960140173;5615.101583746663;5615.9157612635845;4453.977730111394;4805.111054454937;5106.234064890222;5154.175331946945;5216.26972223889;4172.248995180836;4190.28765109932;4212.873613791591;4262.445882035316;4263.914721705549;1359.9048066635241;1374.8006539051783;1376.126159888458;1398.8391334996402;1404.368034384531;775.3758224629115;876.5660997806127;949.8622699708642;968.8692026576322;991.476864714422;5801.53587712982;5922.940770592325;5962.2249107615735;5993;6014.127951447103;941.6751220828803;967.8064387122604;970.0784254957819;1001.6994901529657;1049.58541376156;3856.9421458678144;3985.601439856049;4069.2268468990796;4071.714979951375;4093.9181216375505;10696.465176741132;11289.266197697123;11309.434528273554;11310.660680179608;11313.547458101584;10497.160227181806;10748.247622666338;10969.451527423756;11194;11230.55077796879;8117.918820811835;8326.625301861432;8343.8331233375;8345.582720863933;8630.26642735362;5754.597178197486;5923.7926672566255;5971.58198926071;6029.939700603113;6084.817455476343;9896.059874311928;9896.307258491837;9903.405795652285;9947.950260248652;10112.494375281354;10010.64962726301;10112.64605738825;10253.179745620482;10290.948545257525;10419.94790026045;7999.800004999922;8118.391120665856;8139;8145.551994640352;8160;8544.18830211129;8653.826923461622;8724.825503490021;8740.956295218482;8741.825163496567;7392.815179620337;7422.591757453392;7584.810379740551;7809.570473624005;7886.72396466121;7234.927650723425;7236.457265704988;7249.383802376857;7795.4543181976715;7811.882821757792;6661.1007513985;6745.797626071226;6769.120014398097;6770.593764374154;7048.894266586108;5235.738213089407;5280.419153893145;5316.6544174628525;5340.706261155674;5396.190571414162;5328.520433161034;5418.701971391612;5440.455954404528;5468.343798744176;5500.9174862377895 10:08:02 INFO - PROCESS | 31452 | |4;prototype.html;362.31341607653496;367.49285985340236;370.33524822942826;373.1306056887453;378.17557724161315;5243.475652434755;5317.760700768465;5374.543163831074;5434.619576629632;5469.398366179722;5205.973970130153;5308.362996440433;5345;5361.115415956372;5368.62419630625;4307.85841751936;4308.6553075753945;4310.267254566731;4311.267084595611;4318.9784051079705;4201.201771663389;4306.720063195898;4308.676849236304;4323.459567554056;4327.437433133704;4250.659947204216;4266;4270.274053410912;4274.829006839723;4283.978580107095;4240.434537105053;4247.277962746341;4255.787210639455;4261.083866968616;4292;3628.2017956049626;3728.5339332583426;3752.4746535485056;3759.4360845873066;3861.131245469747;5184.351956005499;5188.377394712621;5192.480751924815;5194.740262986873;5218.973905130488;4831.710097394168;4836.903261934742;4839.7338146401935;4840.5159484051665;4859.927101093486;5406.324209473816;5544.500994910478;5584.984108581361;5586.6871285247935;5608.831735047955;4115.238680844053;4122.711410201287;4140.15126898985;4141.565135660742;4143.6892233082635;5714.314282286111;5716.371199168088;5729.083346664555;5736.483716465496;5755.194272801811;4046.386621540947;4079.4492743479846;4156.334986402222;4164.250434921743;4184.309588917795;4206.894827629334;4218.472690913636;4219.51475580306;4223.458437670228;4235.915281694411;4765.285207218945;4775.42694876617;4786.473487916326;4788.425388953278;4792.544708252711;5337.2794672719465;5574.7353274744855;5577.860553486195;5599.832005039855;5714.142878568165;1361.3125371687236;1385.1342910680823;1410.4076287959083;1431.7566013777682;1433.7490939085826;977.4037836919473;1001.1740314240781;1005.7234260578253;1006.3861044762689;1011.3780025284398;5291.894162116813;5294.258803767476;5306.416294207634;5309.389420216725;5312.973435132877;5511;5511.834644960658;5512.476314750093;5559.444055594408;5573.331200255995;5156.793728250828;5217.217417387346;5272.604554658339;5296.682199068068;5321.521063104339;2910.4969726035592;2928.3996780659913;2929.8974535891566;2932.912012639624;2943.7939344245697;5722.54219662426;5738.68437235956;5739.33559267816;5768.278965129359;5769.432602491935;5466.617336786467;5597.0261114608265;5650.406707295757;5654.943450565524;5682.318121825325;5699.458551437607;5721.768510280994;5742.105105315236;5744.798932037359;5750.66461452365;5238.476152384731;5478.616496845342;5485.546330222414;5495.318136848241;5532.2254884317035;5407.351117865724;5416.593755468434;5436.320459942507;5446.591505637172;5459.553218397048;5695.629784064023;5709.001849352693;5724.883647688661;5739.053056245672;5757.65454072757;3946.9343277314388;4097.610726980932;4108.6713062954295;4149.8340066397;4186.895327616834;5550.7779688812;5803.158542011301;5812.331581868139;5829.854253643523;5835.533157347487;5532.28080349552;5555.194561767523;5575.553955683455;5580.748866300945;5587.832365029056;5367.892642147057;5656.953463609246;5661.037623603915;5670.914936276042;5671.858203544944;5512.972435137799;5638.85902852432;5645.40723224072;5662.688552129673;5697.544196464356;5718;5719.28508936383;5736.68448235351;5740.225069615549;5744.885102297847;5455.208994695826;5597.832065037893;5610.49505544503;5612.849365880066;5615.719214039364 10:08:02 INFO - PROCESS | 31452 | |5;yui.html;780.8594452998458;794.6571984106855;830.178123657579;868.665563757953;884.7390019944114;14749;15442;15783.368665253376;16236.350545978146;16256.75614865778;13189.472421103144;13298.601041968732;13304.93347533261;13308.733825323488;13335.733285334287;700.5866538742141;715.1989771455976;838.0446291228005;853.8335024670192;855.9871601925976;821.8520666280067;827.2389401750374;837.1001173738225;850.2347886901807;854.3336197765752;885.4465958775766;891.5096696816757;893.0578239956843;893.1247377569986;893.2541327991136;748.4611080022376;755.920628334028;878.1832895407236;883.5273128876053;886.2776836877956;630.8391360203165;634.5399585300667;643.0964494884695;654.5647144648802;654.8264709851893;14334.49829255971;14392.568222953223;14396.568102956824;14402.639934001629;14409.207493587848;13216.273104979222;13361.532346367927;13515.121517101357;13559.186448813103;13602;13049.54326598574;13105.475780968749;13186.67033324165;13191.604251872459;13424.664383390396;4365.170617582649;4369.956300437018;4385.2106620808245;4470.083632855257;4483.977580112079;63149.36850631481;63723;64258;64424.677876610316;65060.349396506375;10913.672589822318;11039.46581622474;11220.326780393203;11292.040176585082;11361.829572556418;20485.89757051235;20664;20668.276610318568;20675.27636532744;20725.89637051805;7685.42359323053;7796.961015194887;7920.920790792018;7927.722529711547;8145.104038555754;4555.886102847456;4597.816087356468;4677.555632214934;4740.881477963078;4921.237208232729;3350.798952062884;3411.2495251045225;3446.155691855461;3449.3273811606496;3450.551428314303;599.3766482857866;616.5745635511482;616.8087892753172;619.5260625621403;621.5431657731564;630.5428564290852;657.3328072006917;698.5668885291152;728.5956294256632;754.6264599023518;9446.244300455946;9593.760155996157;9687.70936871895;10158.441285729208;10185.439800810878;633.9809805705836;722.8337482378977;731.4075598765018;739.8964145019702;740.4779630360583;4667.509911459248;4746.28805679144;4752.6197904168275;4757.26262429324;4821.5178482152205;53464.46535534673;53661.731691341294;86086;96540;99581;65147.674261629334;75779;77461.6126919373;78336.2166378329;78741.60629196817;69055.3094469059;70468.64765676139;72920.27079729135;74965.25034749691;75396.24603754003;10297.3821570706;10500.264981451224;10679.412632305297;10713.89286107129;10725.410102444128;46575.76712116418;47167.76416117897;47376;47877.28184077311;48599;58058.41941580699;62042.0693689604;62510;63379.36620633735;63770.36229637644;12693.174943628635;12737;12803.61589152327;12956.093073484766;13048.217106973612;14002.36989335462;14211;14575.125492470486;14720.411183552538;14957;13235.404406801525;13593;13980.720385592434;14322.212278324376;14488.565343039725;13621.863781362455;13673.179609223478;13834;14170.07894486855;14310.513775797159;13176.604701858576;13430.127041742646;13480.79778803299;13572;14253.429862805786;11206.7758644825;11373.83897059556;11579.710507237387;11580.478878450658;11647.59233426826;11614.88385116138;11725.941370293434;11727.941360293144;11998.520059197883;12014.879851201376 10:08:02 INFO - PROCESS | 31452 | __end_tp_report 10:08:02 INFO - PROCESS | 31452 | __start_cc_report 10:08:02 INFO - PROCESS | 31452 | _x_x_mozilla_cycle_collect,994 10:08:02 INFO - PROCESS | 31452 | __end_cc_report 10:08:02 INFO - PROCESS | 31452 | __startTimestamp1479146882815__endTimestamp 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | ------- Summary: start ------- 10:08:02 INFO - PROCESS | 31452 | Number of tests: 6 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#0] dojo.html Cycles:165 Average:5416.95 Median:5637.58 stddev:608.31 (10.8%) stddev-sans-first:582.42 10:08:02 INFO - PROCESS | 31452 | Values: 3101.1 3145.2 3147.0 3161.9 3164.0 5382.8 5434.6 5437.3 5469.8 5477.9 5428.9 5446.6 5466.9 5471.7 5485.4 4931.5 4980.4 4989.0 5031.6 5033.3 4850.0 4991.4 5050.3 5051.8 5080.5 4838.8 4902.0 4906.6 4913.8 4945.4 4944.3 4950.4 4983.3 5044.7 5067.9 4968.3 4983.6 5000.7 5005.1 5030.6 5180.0 5292.1 5299.8 5343.2 5396.9 5716.1 5754.8 5786.2 5791.3 5802.6 5811.8 5812.2 5826.3 5842.1 5859.7 5296.7 5333.9 5382.1 5492.4 5511.7 5943.9 5962.8 5965.2 5967.9 5989.6 5714.8 5736.0 5738.5 5738.9 5748.7 5630.8 5694.0 5711.4 5751.1 5764.7 5778.8 5806.1 5810.5 5820.4 5827.6 5723.9 5780.0 5797.9 5808.8 5810.4 4396.5 4416.0 4478.6 4527.6 4530.9 4029.6 4047.8 4061.9 4063.9 4083.1 5561.6 5606.2 5608.9 5617.7 5621.4 5657.1 5724.2 5741.2 5862.5 5872.4 5506.7 5574.1 5580.5 5595.0 5607.0 4963.6 5001.2 5008.6 5011.9 5016.3 5868.3 5876.1 6056.3 6060.8 6072.7 5542.2 5759.3 5815.0 5852.7 5867.2 6104.7 6109.1 6109.3 6111.9 6115.3 5525.4 5798.6 5799.7 5801.4 5808.6 5637.6 5804.9 5836.6 5846.4 5968.2 5796.1 5809.9 5812.6 5842.9 5846.9 5661.5 5792.6 5793.6 5795.5 5797.3 5477.4 5528.8 5647.4 5712.4 5712.5 5791.8 5792.0 5793.5 5800.5 5806.1 5492.5 5550.4 5649.5 5657.4 5663.0 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#1] ext.html Cycles:180 Average:63140.27 Median:23456.21 stddev:128849.49 (549.3%) stddev-sans-first:129125.30 10:08:02 INFO - PROCESS | 31452 | Values: 570.7 617.8 631.1 653.5 657.1 33558.8 34181.7 37007.6 44224.0 45392.1 46042.0 46313.1 46405.8 46408.0 46461.0 8797.8 8960.4 8966.1 8969.5 8970.4 8739.7 8828.9 8830.3 8834.9 8843.3 8800.1 8840.2 8840.8 8854.3 8854.3 8810.0 8853.6 8864.1 8865.0 8868.3 8704.4 8778.9 8783.3 8784.5 8792.8 42649.1 43032.4 43077.8 43088.6 43169.1 3880.9 3910.0 3925.6 4179.2 4344.3 32440.8 32510.2 32807.8 32938.3 34503.0 4121.8 4254.1 4258.9 4263.3 4316.3 350353.0 351402.0 351836.0 352540.0 352983.2 54192.7 54307.5 54327.5 54382.5 54432.0 39905.4 40419.2 40622.8 42419.8 42427.6 19155.3 19384.2 19397.8 19399.5 19403.7 12698.8 12700.0 12704.9 12706.0 12709.8 20321.8 20377.8 20477.6 20485.6 20520.9 7166.6 7179.4 7190.4 7269.0 7413.3 982.0 1065.6 1069.9 1081.5 1125.2 32930.5 33003.8 33319.3 33360.5 33365.3 1082.1 1090.9 1108.4 1125.6 1171.9 16484.3 17214.4 17619.1 17874.6 17998.6 411664.0 619308.0 627198.0 627978.0 628369.0 60935.0 61556.0 61667.4 61680.4 61712.7 1306.4 1365.3 1373.2 1413.5 1425.6 31813.7 35864.6 35956.5 35978.6 36014.8 58483.4 59022.4 59078.0 59088.0 59255.7 477985.0 482574.0 482927.0 484049.0 486649.0 30363.4 30969.8 31422.5 33076.7 33206.8 50210.5 50415.2 50731.0 50874.5 50969.2 41479.9 42932.4 43015.8 43711.0 44425.3 42989.6 43024.8 43903.8 44348.0 45111.0 37684.8 38470.6 38769.0 38890.4 43771.1 35011.5 38843.0 38949.6 39134.2 39358.6 28613.3 28725.4 28783.6 29100.9 29150.7 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#2] jquery.html Cycles:180 Average:28870.62 Median:2792.22 stddev:77098.56 (2761.2%) stddev-sans-first:77285.27 10:08:02 INFO - PROCESS | 31452 | Values: 433.8 433.8 433.9 441.2 447.9 18651.4 18975.2 20110.4 20310.2 20418.7 16041.3 19474.4 20114.0 20728.5 20731.7 5130.5 5391.5 5393.3 5399.6 5402.9 5333.8 5337.9 5413.0 5415.1 5904.9 5870.5 5892.2 5917.1 5919.1 5920.1 5477.2 5519.3 5529.9 5903.4 5915.3 5358.4 5361.5 5366.0 5366.1 5375.8 15187.8 15659.1 23510.2 23580.8 24408.4 2462.0 2607.2 2783.8 2800.6 3013.3 10432.5 10995.5 11280.8 11424.5 11485.7 1942.4 1966.1 2007.9 2011.3 2052.7 71070.0 85661.6 91132.5 92575.1 93865.0 11711.9 11862.8 11906.4 12186.9 12322.6 17197.9 17282.5 17361.0 17374.1 17388.6 8345.9 8539.5 8680.3 8971.4 9011.6 8172.3 8644.5 8657.2 8897.0 8941.7 5072.2 5158.9 5173.5 5192.4 5292.3 1992.5 2043.8 2079.7 2133.4 2134.2 950.1 984.6 994.7 1043.6 1064.3 19013.7 19932.7 20163.4 20177.7 20469.7 982.0 990.3 1032.1 1033.3 1046.9 10391.6 11206.7 11292.4 11308.1 11472.5 461249.0 464319.0 465756.0 466403.0 470840.0 81058.0 83821.2 97206.5 99484.0 101284.0 952.1 974.8 982.7 1011.3 1020.5 17622.7 17862.9 18130.6 18148.3 18425.8 43508.8 43785.6 43940.6 44376.0 45582.0 60831.7 61168.4 61558.4 61994.7 63503.7 5779.1 7523.4 7878.7 8849.9 9342.2 12395.2 13002.9 13424.9 13770.3 13974.7 11592.3 11866.2 12054.8 12328.6 12605.2 11515.8 11548.4 11791.2 12009.3 12408.4 11967.5 12059.8 12067.2 12081.3 12111.0 8926.9 8984.7 9037.0 9232.6 9346.2 11615.7 12111.3 12146.3 12179.9 12622.5 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#3] mootools.html Cycles:180 Average:5907.73 Median:5130.20 stddev:3276.16 (63.9%) stddev-sans-first:3268.67 10:08:02 INFO - PROCESS | 31452 | Values: 1507.9 1533.7 1669.8 1790.6 1792.0 8546.4 9126.0 9134.9 9136.8 9150.4 7580.7 7847.8 8570.0 8753.2 8772.3 2514.5 2536.7 2543.3 2543.4 2543.5 2485.3 2528.5 2543.0 2545.4 2546.1 2349.0 2380.0 2503.5 2506.8 2510.0 2434.0 2437.5 2439.8 2446.6 2463.6 2414.5 2429.3 2448.7 2450.2 2471.3 8980.1 9010.8 9051.8 9062.8 9063.9 5475.0 5614.5 5625.2 5625.8 5627.1 3543.7 3634.7 3715.8 3728.6 3736.4 1176.8 1193.8 1228.6 1256.4 1281.9 12352.3 12358.8 12428.5 12441.1 12464.1 4656.0 4862.6 4881.5 4940.7 5005.4 10431.6 10459.0 10478.2 10481.1 10488.6 5594.3 5606.2 5608.0 5615.1 5615.9 4454.0 4805.1 5106.2 5154.2 5216.3 4172.2 4190.3 4212.9 4262.4 4263.9 1359.9 1374.8 1376.1 1398.8 1404.4 775.4 876.6 949.9 968.9 991.5 5801.5 5922.9 5962.2 5993.0 6014.1 941.7 967.8 970.1 1001.7 1049.6 3856.9 3985.6 4069.2 4071.7 4093.9 10696.5 11289.3 11309.4 11310.7 11313.5 10497.2 10748.2 10969.5 11194.0 11230.6 8117.9 8326.6 8343.8 8345.6 8630.3 5754.6 5923.8 5971.6 6029.9 6084.8 9896.1 9896.3 9903.4 9948.0 10112.5 10010.6 10112.6 10253.2 10290.9 10419.9 7999.8 8118.4 8139.0 8145.6 8160.0 8544.2 8653.8 8724.8 8741.0 8741.8 7392.8 7422.6 7584.8 7809.6 7886.7 7234.9 7236.5 7249.4 7795.5 7811.9 6661.1 6745.8 6769.1 6770.6 7048.9 5235.7 5280.4 5316.7 5340.7 5396.2 5328.5 5418.7 5440.5 5468.3 5500.9 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#4] prototype.html Cycles:180 Average:4699.10 Median:5311.18 stddev:1346.61 (25.4%) stddev-sans-first:1310.45 10:08:02 INFO - PROCESS | 31452 | Values: 362.3 367.5 370.3 373.1 378.2 5243.5 5317.8 5374.5 5434.6 5469.4 5206.0 5308.4 5345.0 5361.1 5368.6 4307.9 4308.7 4310.3 4311.3 4319.0 4201.2 4306.7 4308.7 4323.5 4327.4 4250.7 4266.0 4270.3 4274.8 4284.0 4240.4 4247.3 4255.8 4261.1 4292.0 3628.2 3728.5 3752.5 3759.4 3861.1 5184.4 5188.4 5192.5 5194.7 5219.0 4831.7 4836.9 4839.7 4840.5 4859.9 5406.3 5544.5 5585.0 5586.7 5608.8 4115.2 4122.7 4140.2 4141.6 4143.7 5714.3 5716.4 5729.1 5736.5 5755.2 4046.4 4079.4 4156.3 4164.3 4184.3 4206.9 4218.5 4219.5 4223.5 4235.9 4765.3 4775.4 4786.5 4788.4 4792.5 5337.3 5574.7 5577.9 5599.8 5714.1 1361.3 1385.1 1410.4 1431.8 1433.7 977.4 1001.2 1005.7 1006.4 1011.4 5291.9 5294.3 5306.4 5309.4 5313.0 5511.0 5511.8 5512.5 5559.4 5573.3 5156.8 5217.2 5272.6 5296.7 5321.5 2910.5 2928.4 2929.9 2932.9 2943.8 5722.5 5738.7 5739.3 5768.3 5769.4 5466.6 5597.0 5650.4 5654.9 5682.3 5699.5 5721.8 5742.1 5744.8 5750.7 5238.5 5478.6 5485.5 5495.3 5532.2 5407.4 5416.6 5436.3 5446.6 5459.6 5695.6 5709.0 5724.9 5739.1 5757.7 3946.9 4097.6 4108.7 4149.8 4186.9 5550.8 5803.2 5812.3 5829.9 5835.5 5532.3 5555.2 5575.6 5580.7 5587.8 5367.9 5657.0 5661.0 5670.9 5671.9 5513.0 5638.9 5645.4 5662.7 5697.5 5718.0 5719.3 5736.7 5740.2 5744.9 5455.2 5597.8 5610.5 5612.8 5615.7 10:08:02 INFO - PROCESS | 31452 | 10:08:02 INFO - PROCESS | 31452 | [#5] yui.html Cycles:180 Average:17928.50 Median:4672.53 stddev:23135.31 (495.1%) stddev-sans-first:23164.38 10:08:02 INFO - PROCESS | 31452 | Values: 780.9 794.7 830.2 868.7 884.7 14749.0 15442.0 15783.4 16236.4 16256.8 13189.5 13298.6 13304.9 13308.7 13335.7 700.6 715.2 838.0 853.8 856.0 821.9 827.2 837.1 850.2 854.3 885.4 891.5 893.1 893.1 893.3 748.5 755.9 878.2 883.5 886.3 630.8 634.5 643.1 654.6 654.8 14334.5 14392.6 14396.6 14402.6 14409.2 13216.3 13361.5 13515.1 13559.2 13602.0 13049.5 13105.5 13186.7 13191.6 13424.7 4365.2 4370.0 4385.2 4470.1 4484.0 63149.4 63723.0 64258.0 64424.7 65060.3 10913.7 11039.5 11220.3 11292.0 11361.8 20485.9 20664.0 20668.3 20675.3 20725.9 7685.4 7797.0 7920.9 7927.7 8145.1 4555.9 4597.8 4677.6 4740.9 4921.2 3350.8 3411.2 3446.2 3449.3 3450.6 599.4 616.6 616.8 619.5 621.5 630.5 657.3 698.6 728.6 754.6 9446.2 9593.8 9687.7 10158.4 10185.4 634.0 722.8 731.4 739.9 740.5 4667.5 4746.3 4752.6 4757.3 4821.5 53464.5 53661.7 86086.0 96540.0 99581.0 65147.7 75779.0 77461.6 78336.2 78741.6 69055.3 70468.6 72920.3 74965.3 75396.2 10297.4 10500.3 10679.4 10713.9 10725.4 46575.8 47167.8 47376.0 47877.3 48599.0 58058.4 62042.1 62510.0 63379.4 63770.4 12693.2 12737.0 12803.6 12956.1 13048.2 14002.4 14211.0 14575.1 14720.4 14957.0 13235.4 13593.0 13980.7 14322.2 14488.6 13621.9 13673.2 13834.0 14170.1 14310.5 13176.6 13430.1 13480.8 13572.0 14253.4 11206.8 11373.8 11579.7 11580.5 11647.6 11614.9 11725.9 11727.9 11998.5 12014.9 10:08:02 INFO - PROCESS | 31452 | -------- Summary: end -------- 10:08:02 INFO - PROCESS | 31452 | 10:08:03 INFO - TEST-INFO | 31452: exit 0 10:08:03 INFO - TEST-OK | dromaeo_css | took 1115223ms 10:08:03 INFO - TEST-START | kraken 10:08:03 INFO - operating with platform_type : linux_ 10:08:03 INFO - Initialising browser for kraken test... 10:08:03 INFO - TEST-INFO | started process 31768 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptli5Bo/profile http://localhost:33416/getInfo.html) 10:08:06 ERROR - PROCESS | 31768 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:08:07 INFO - PROCESS | 31768 | __metrics Screen width/height:1600/1200 10:08:07 INFO - PROCESS | 31768 | colorDepth:24 10:08:07 INFO - PROCESS | 31768 | Browser inner width/height: 1024/697 10:08:07 INFO - PROCESS | 31768 | __metrics 10:08:08 INFO - PROCESS | 31768 | 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" 10:08:08 INFO - PROCESS | 31768 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:08:08 INFO - PROCESS | 31768 | console.error: PushService: 10:08:08 INFO - PROCESS | 31768 | stateChangeProcessEnqueue: Error transitioning state 10:08:08 INFO - PROCESS | 31768 | UnknownError 10:08:09 INFO - TEST-INFO | 31768: exit 0 10:08:09 INFO - Browser initialized. 10:08:09 INFO - Running cycle 1/1 for kraken test... 10:08:09 INFO - TEST-INFO | started process 31858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptli5Bo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:21 INFO - PROCESS | 31858 | RSS: Main: 144732160 10:08:21 INFO - PROCESS | 31858 | 10:08:23 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33416/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:24 INFO - PROCESS | 31858 | RSS: Main: 186298368 10:08:24 INFO - PROCESS | 31858 | 10:08:27 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33416/tests/kraken/driver.html?testName=audio-dft) 10:08:27 INFO - PROCESS | 31858 | RSS: Main: 175927296 10:08:27 INFO - PROCESS | 31858 | 10:08:30 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33416/tests/kraken/driver.html?testName=audio-fft) 10:08:31 INFO - PROCESS | 31858 | RSS: Main: 266940416 10:08:31 INFO - PROCESS | 31858 | 10:08:33 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33416/tests/kraken/driver.html?testName=audio-oscillator) 10:08:33 INFO - PROCESS | 31858 | RSS: Main: 173563904 10:08:33 INFO - PROCESS | 31858 | 10:08:35 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33416/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:36 INFO - PROCESS | 31858 | RSS: Main: 175771648 10:08:36 INFO - PROCESS | 31858 | 10:08:39 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33416/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:40 INFO - PROCESS | 31858 | RSS: Main: 214745088 10:08:40 INFO - PROCESS | 31858 | 10:08:43 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33416/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:44 INFO - PROCESS | 31858 | RSS: Main: 212381696 10:08:44 INFO - PROCESS | 31858 | 10:08:46 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33416/tests/kraken/driver.html?testName=json-parse-financial) 10:08:47 INFO - PROCESS | 31858 | RSS: Main: 211427328 10:08:47 INFO - PROCESS | 31858 | 10:08:48 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33416/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:49 INFO - PROCESS | 31858 | RSS: Main: 174043136 10:08:49 INFO - PROCESS | 31858 | 10:08:51 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:51 INFO - PROCESS | 31858 | RSS: Main: 180400128 10:08:51 INFO - PROCESS | 31858 | 10:08:53 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:54 INFO - PROCESS | 31858 | RSS: Main: 190509056 10:08:54 INFO - PROCESS | 31858 | 10:08:56 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:57 INFO - PROCESS | 31858 | RSS: Main: 198295552 10:08:57 INFO - PROCESS | 31858 | 10:09:00 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:00 INFO - PROCESS | 31858 | RSS: Main: 195211264 10:09:00 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | Cycle 1(1): loaded http://localhost:33416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33416/tests/kraken/driver.html?testName=ai-astar) 10:09:02 INFO - PROCESS | 31858 | RSS: Main: 246808576 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | __start_tp_report 10:09:02 INFO - PROCESS | 31858 | _x_x_mozilla_page_load 10:09:02 INFO - PROCESS | 31858 | _x_x_mozilla_page_load_details 10:09:02 INFO - PROCESS | 31858 | |i|pagename|runs| 10:09:02 INFO - PROCESS | 31858 | |0;ai-astar;111;115;114;112;112;116;112;112;113;111 10:09:02 INFO - PROCESS | 31858 | |1;audio-beat-detection;155;129;121;167;135;126;124;121;148;122 10:09:02 INFO - PROCESS | 31858 | |2;audio-dft;161;160;162;161;161;160;160;162;160;160 10:09:02 INFO - PROCESS | 31858 | |3;audio-fft;74;76;76;102;76;75;76;77;106;76 10:09:02 INFO - PROCESS | 31858 | |4;audio-oscillator;81;81;126;82;82;82;99;82;82;119 10:09:02 INFO - PROCESS | 31858 | |5;imaging-gaussian-blur;117;118;117;117;117;116;117;117;117;117 10:09:02 INFO - PROCESS | 31858 | |6;imaging-darkroom;146;146;147;146;146;146;147;146;145;147 10:09:02 INFO - PROCESS | 31858 | |7;imaging-desaturate;85;85;85;85;85;85;85;84;85;84 10:09:02 INFO - PROCESS | 31858 | |8;json-parse-financial;52;50;50;49;48;48;48;49;48;50 10:09:02 INFO - PROCESS | 31858 | |9;json-stringify-tinderbox;59;58;60;59;59;59;58;59;59;59 10:09:02 INFO - PROCESS | 31858 | |10;stanford-crypto-aes;75;74;82;78;76;75;75;83;82;76 10:09:02 INFO - PROCESS | 31858 | |11;stanford-crypto-ccm;141;133;128;123;130;122;130;122;164;134 10:09:02 INFO - PROCESS | 31858 | |12;stanford-crypto-pbkdf2;155;154;153;162;154;154;155;152;155;153 10:09:02 INFO - PROCESS | 31858 | |13;stanford-crypto-sha256-iterative;62;62;62;61;65;61;61;62;61;58 10:09:02 INFO - PROCESS | 31858 | __end_tp_report 10:09:02 INFO - PROCESS | 31858 | __start_cc_report 10:09:02 INFO - PROCESS | 31858 | _x_x_mozilla_cycle_collect,1609 10:09:02 INFO - PROCESS | 31858 | __end_cc_report 10:09:02 INFO - PROCESS | 31858 | __startTimestamp1479146942679__endTimestamp 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | ------- Summary: start ------- 10:09:02 INFO - PROCESS | 31858 | Number of tests: 14 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#0] ai-astar Cycles:10 Average:112.80 Median:112.50 stddev:1.69 (1.5%) stddev-sans-first:1.66 10:09:02 INFO - PROCESS | 31858 | Values: 111.0 115.0 114.0 112.0 112.0 116.0 112.0 112.0 113.0 111.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:132.00 stddev:16.30 (12.3%) stddev-sans-first:15.57 10:09:02 INFO - PROCESS | 31858 | Values: 155.0 129.0 121.0 167.0 135.0 126.0 124.0 121.0 148.0 122.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 10:09:02 INFO - PROCESS | 31858 | Values: 161.0 160.0 162.0 161.0 161.0 160.0 160.0 162.0 160.0 160.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.97 (15.8%) stddev-sans-first:12.40 10:09:02 INFO - PROCESS | 31858 | Values: 74.0 76.0 76.0 102.0 76.0 75.0 76.0 77.0 106.0 76.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:82.00 stddev:17.24 (21.0%) stddev-sans-first:17.85 10:09:02 INFO - PROCESS | 31858 | Values: 81.0 81.0 126.0 82.0 82.0 82.0 99.0 82.0 82.0 119.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 10:09:02 INFO - PROCESS | 31858 | Values: 117.0 118.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:09:02 INFO - PROCESS | 31858 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 145.0 147.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 10:09:02 INFO - PROCESS | 31858 | Values: 85.0 85.0 85.0 85.0 85.0 85.0 85.0 84.0 85.0 84.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.50 stddev:1.32 (2.7%) stddev-sans-first:0.93 10:09:02 INFO - PROCESS | 31858 | Values: 52.0 50.0 50.0 49.0 48.0 48.0 48.0 49.0 48.0 50.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 10:09:02 INFO - PROCESS | 31858 | Values: 59.0 58.0 60.0 59.0 59.0 59.0 58.0 59.0 59.0 59.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:77.00 stddev:3.44 (4.5%) stddev-sans-first:3.52 10:09:02 INFO - PROCESS | 31858 | Values: 75.0 74.0 82.0 78.0 76.0 75.0 75.0 83.0 82.0 76.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#11] stanford-crypto-ccm Cycles:10 Average:132.70 Median:131.50 stddev:12.52 (9.5%) stddev-sans-first:12.91 10:09:02 INFO - PROCESS | 31858 | Values: 141.0 133.0 128.0 123.0 130.0 122.0 130.0 122.0 164.0 134.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:154.50 stddev:2.75 (1.8%) stddev-sans-first:2.92 10:09:02 INFO - PROCESS | 31858 | Values: 155.0 154.0 153.0 162.0 154.0 154.0 155.0 152.0 155.0 153.0 10:09:02 INFO - PROCESS | 31858 | 10:09:02 INFO - PROCESS | 31858 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.72 (2.8%) stddev-sans-first:1.81 10:09:02 INFO - PROCESS | 31858 | Values: 62.0 62.0 62.0 61.0 65.0 61.0 61.0 62.0 61.0 58.0 10:09:02 INFO - PROCESS | 31858 | -------- Summary: end -------- 10:09:02 INFO - PROCESS | 31858 | 10:09:03 INFO - TEST-INFO | 31858: exit 0 10:09:03 INFO - TEST-OK | kraken | took 60106ms 10:09:03 INFO - SUITE-END | took 1175s 10:09:03 INFO - Completed test suite (00:19:35) 10:09:03 INFO - javascript benchmark 10:09:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.0851798719377, 3145.24514116612, 3146.9527957080663, 3161.8735250589975, 3164.0349693343537, 5382.811601593945, 5434.592405569589, 5437.32033495813, 5469.78120875165, 5477.877035207783, 5428.9185662214995, 5446.564274858012, 5466.9179962300595, 5471.699056551888, 5485.396606373296, 4931.482194369584, 4980.4272508661425, 4989.027139707759, 5031.647784655075, 5033.320501732256, 4850.0, 4991.376077990251, 5050.267711181877, 5051.848444546669, 5080.542751152396, 4838.806447742086, 4902.044101400228, 4906.558409743122, 4913.778879950393, 4945.431275403321, 4944.258361245806, 4950.381202349707, 4983.302337672729, 5044.747762611892, 5067.949320506821, 4968.279599458095, 4983.551480366748, 5000.699958002495, 5005.124103281911, 5030.597552195815, 5179.974100129513, 5292.073887069786, 5299.761510732026, 5343.225232341328, 5396.946030539723, 5716.056850619642, 5754.769809207627, 5786.161006654053, 5791.305043394819, 5802.5938184327115, 5811.767529298823, 5812.244408226903, 5826.300843898759, 5842.123681447817, 5859.707014649293, 5296.655717378397, 5333.893322133536, 5382.138857782736, 5492.423295553909, 5511.724413779295, 5943.881122377528, 5962.791302304398, 5965.194698715704, 5967.880642387215, 5989.610675306091, 5714.771409143671, 5736.0, 5738.540916726652, 5738.913916291259, 5748.655080695172, 5630.802921897796, 5694.0, 5711.400302968212, 5751.13732940054, 5764.682942438122, 5778.8266352009505, 5806.1000544915605, 5810.477057064884, 5820.359760426434, 5827.5920685552455, 5723.856903577361, 5780.046292361713, 5797.855053623609, 5808.767649294065, 5810.360860305363, 4396.516383197845, 4416.0, 4478.596926276586, 4527.569880861313, 4530.864074077783, 4029.6373326400767, 4047.7571345719352, 4061.898452538651, 4063.918721625551, 4083.060895993938, 5561.555075593943, 5606.159076138611, 5608.915866262009, 5617.747201375948, 5621.4378562144275, 5657.066584013674, 5724.198612194296, 5741.224934633855, 5862.501687356623, 5872.442117998783, 5506.669599824023, 5574.08027675429, 5580.469855363734, 5595.0, 5607.046802043662, 4963.60291176705, 5001.19980803077, 5008.574271186917, 5011.924821127686, 5016.272640467112, 5868.295804503487, 5876.059830427283, 6056.3338032816355, 6060.818175454831, 6072.726727297282, 5542.168674698827, 5759.280089988752, 5815.0, 5852.678102704393, 5867.20792692998, 6104.69476526163, 6109.053096770008, 6109.2974307955155, 6111.8777624446375, 6115.266168059861, 5525.4474552544425, 5798.623089499169, 5799.710014499343, 5801.361850196559, 5808.593398462067, 5637.57718171147, 5804.854878627899, 5836.649801011783, 5846.444587764156, 5968.224130863134, 5796.072628379439, 5809.854753631024, 5812.593118481665, 5842.883142337215, 5846.912296315473, 5661.547076233809, 5792.5655575832825, 5793.594448388572, 5795.507381872588, 5797.275340582427, 5477.397486276427, 5528.778848846001, 5647.3505546861, 5712.400197979152, 5712.514436272963, 5791.768329266951, 5791.971040144772, 5793.478586927196, 5800.535957123504, 5806.129080637938, 5492.5331346835965, 5550.389457159628, 5649.491545760901, 5657.377688454185, 5663.03728366187], "value": 5376.330581887457, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.7260514952823, 617.7930393318238, 631.1227393922447, 653.5131327161265, 657.1424291299854, 33558.83220583897, 34181.65818341819, 37007.62992370079, 44224.0, 45392.09215815682, 46042.0, 46313.07373852521, 46405.76797116018, 46408.0, 46461.0, 8797.7800554986, 8960.372773905829, 8966.14821591951, 8969.506677132755, 8970.41692289999, 8739.650413983432, 8828.8675669865, 8830.293576513895, 8834.911650883505, 8843.292536597057, 8800.075992020842, 8840.248578870804, 8840.778980525507, 8854.335924805633, 8854.335924805633, 8810.0, 8853.557322133867, 8864.069272726336, 8865.024847266795, 8868.290536757077, 8704.434211776213, 8778.868316975251, 8783.253423459013, 8784.472931624123, 8792.780180495474, 42649.1470170598, 43032.3545146823, 43077.784611077055, 43088.56911430877, 43169.136617267475, 3880.864169754045, 3910.022494376406, 3925.646691797752, 4179.226843034048, 4344.348347747863, 32440.837795811105, 32510.18724531882, 32807.83596082028, 32938.341233175204, 34503.0, 4121.814518346651, 4254.0879750182, 4258.87223383299, 4263.275243208662, 4316.330968699856, 350353.0, 351402.0, 351836.0, 352540.0, 352983.2350838255, 54192.72903635457, 54307.45692543024, 54327.45672543224, 54382.45617543853, 54432.0, 39905.40141897816, 40419.19161616751, 40622.79688601538, 42419.78790106092, 42427.575724242364, 19155.329563465213, 19384.2246310146, 19397.806021939883, 19399.515012124808, 19403.708944365844, 12698.80951785724, 12700.0, 12704.936475317565, 12706.0, 12709.809352859715, 20321.79678203199, 20377.79622203759, 20477.590448190953, 20485.59028819415, 20520.947905209658, 7166.605836678928, 7179.425645948311, 7190.352868241884, 7268.963655181796, 7413.332800047914, 981.9852702209473, 1065.616378103882, 1069.8983596558314, 1081.469720345702, 1125.2404626876826, 32930.50604240938, 33003.83498082494, 33319.33361332808, 33360.49959250613, 33365.332693346, 1082.1288862465633, 1090.8636420447444, 1108.3903852881044, 1125.6229163230246, 1171.9276861671476, 16484.258208380652, 17214.42405606687, 17619.119044047748, 17874.642507149783, 17998.640027199646, 411664.0, 619308.0, 627198.0, 627978.0, 628369.0, 60935.0, 61556.0, 61667.38332616706, 61680.38319616836, 61712.69143654343, 1306.372940988312, 1365.262758110629, 1373.176094343406, 1413.4770135050098, 1425.6079578115894, 31813.68186318107, 35864.64135358613, 35956.4606530897, 35978.64021359753, 36014.819925901254, 58483.4151658478, 59022.40977590169, 59078.0, 59088.0, 59255.70372148284, 477985.0, 482574.0, 482927.0, 484049.0, 486649.0, 30363.39273214479, 30969.8451507741, 31422.528662070545, 33076.66923330736, 33206.83396583002, 50210.497895020584, 50415.24377134419, 50731.0, 50874.49125508698, 50969.23546146885, 41479.92866616646, 42932.35601466043, 43015.784921075196, 43711.0, 44425.333619996374, 42989.57010429856, 43024.84875756262, 43903.78048109739, 44348.0, 45111.0, 37684.811575943044, 38470.615293846706, 38769.0, 38890.416643750934, 43771.12457750891, 35011.54919155635, 38843.0, 38949.6105038946, 39134.217315654096, 39358.60641393549, 28613.28466788347, 28725.42549149047, 28783.568246476738, 29100.854495728232, 29150.7084929148], "value": 20582.130081475483, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.8134602121087, 433.84164779855354, 433.90887913538154, 441.234458214997, 447.88131145246496, 18651.440456786302, 18975.240990360402, 20110.396688099343, 20310.18759249632, 20418.69371959422, 16041.278142483587, 19474.41576752696, 20114.0, 20728.48178795531, 20731.689024664644, 5130.53825155735, 5391.460853914617, 5393.298871146765, 5399.62202645815, 5402.91895621566, 5333.759980800874, 5337.866553336178, 5413.025655382049, 5415.079436495806, 5904.88190236195, 5870.471657550841, 5892.175095486614, 5917.082852157922, 5919.141724449953, 5920.141579470974, 5477.233187353734, 5519.31008623922, 5529.944700552983, 5903.35063143054, 5915.319738230116, 5358.437364076754, 5361.544268737162, 5366.0, 5366.060939335601, 5375.838724838261, 15187.848121518864, 15659.13874736889, 23510.177143800054, 23580.820958951983, 24408.38979025521, 2461.9753802461923, 2607.178738697304, 2783.805133640646, 2800.635917330734, 3013.276813564729, 10432.530536125892, 10995.450227488594, 11280.830787538194, 11424.485898134604, 11485.655430337103, 1942.3978566644385, 1966.1349006437265, 2007.9498012549657, 2011.3161525081396, 2052.6613108837173, 71070.0, 85661.57169214114, 91132.54433727855, 92575.07424925732, 93865.0, 11711.941440292914, 11862.762744745056, 11906.404679765978, 12186.878131218575, 12322.630321090382, 17197.91401043012, 17282.481525554253, 17361.045142517283, 17374.13129343528, 17388.565285867953, 8345.874811877828, 8539.480853148336, 8680.30557555394, 8971.37200395979, 9011.63953441855, 8172.305354044973, 8644.524551149747, 8657.22085012352, 8897.0, 8941.687040953535, 5072.239164125337, 5158.896822063538, 5173.534381905646, 5192.402873669501, 5292.259083728281, 1992.5417154054649, 2043.846711496614, 2079.677649964258, 2133.4026472587702, 2134.1996751218294, 950.1116456113556, 984.6307634637011, 994.7363948553639, 1043.6295115234134, 1064.3347907557777, 19013.714794278098, 19932.70100948487, 20163.39509814708, 20177.701061117885, 20469.692954605693, 982.0081717465412, 990.346371394876, 1032.1226957086567, 1033.3180101133216, 1046.9057784799404, 10391.636292729718, 11206.663800086011, 11292.435378231055, 11308.09535237179, 11472.4837382318, 461249.0, 464319.0, 465756.0, 466403.0, 470840.0, 81058.0, 83821.16178838255, 97206.51396743112, 99484.0, 101284.0, 952.0717300631967, 974.7952929884661, 982.7150126463389, 1011.3325205364424, 1020.4897551224387, 17622.735658965383, 17862.910685446488, 18130.637387251918, 18148.27406903762, 18425.81574184241, 43508.782456087516, 43785.56214437942, 43940.560594393646, 44376.0, 45582.0, 60831.69584152051, 61168.38831611627, 61558.38441615526, 61994.69002654958, 63503.6824815873, 5779.075347944308, 7523.360514356122, 7878.724244651639, 8849.867251991354, 9342.205912497242, 12395.194312369667, 13002.869971300166, 13424.865751342362, 13770.311484425538, 13974.65063373383, 11592.304461732323, 11866.228695134789, 12054.75890482168, 12328.568500102454, 12605.243685378906, 11515.8272625909, 11548.42257887119, 11791.233569817934, 12009.279443233434, 12408.441620127296, 11967.461464234304, 12059.819102713642, 12067.215630984309, 12081.275123492618, 12111.0, 8926.86609700868, 8984.685536006209, 9037.0, 9232.584533696134, 9346.15884570392, 11615.6515304541, 12111.273323600612, 12146.331951742739, 12179.939100304442, 12622.495100195889], "value": 9494.184285123672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.9246037698113, 1533.6702608939077, 1669.8163202047772, 1790.606066665334, 1791.9641607167864, 8546.40175187737, 9126.041765614615, 9134.908650913489, 9136.817263654722, 9150.359474836756, 7580.6967721291085, 7847.843043139133, 8569.95715021424, 8753.212210901018, 8772.254358379545, 2514.5473814713346, 2536.720960694322, 2543.2878793937725, 2543.3514453814346, 2543.529447052292, 2485.2668462803426, 2528.5448619248527, 2543.0, 2545.350935511442, 2546.1088618983395, 2349.0486353026968, 2379.95240095198, 2503.4993001399703, 2506.8119891008155, 2509.987450062756, 2433.9634905476432, 2437.463757973243, 2439.8048156147465, 2446.559619268531, 2463.5935070713313, 2414.4929564791414, 2429.307647320505, 2448.6939132608422, 2450.1669432392896, 2471.2586224132688, 8980.14688604588, 9010.819783604291, 9051.778009968702, 9062.77343066422, 9063.909360906373, 5475.0, 5614.522765564931, 5625.212470254168, 5625.774969001235, 5627.099664053731, 3543.698785603227, 3634.745567810254, 3715.8327875245677, 3728.5898551159353, 3736.40217565191, 1176.8352430659716, 1193.8209268609692, 1228.582282024116, 1256.447163248168, 1281.9166754161042, 12352.320622365767, 12358.75282494345, 12428.502859885593, 12441.129120961536, 12464.065195110403, 4655.96592178493, 4862.635302352338, 4881.4630390657, 4940.654154209243, 5005.399352077774, 10431.5827366906, 10459.0, 10478.214133939984, 10481.056704896597, 10488.580456781643, 5594.328680558359, 5606.159076138611, 5607.971960140173, 5615.101583746663, 5615.9157612635845, 4453.977730111394, 4805.111054454937, 5106.234064890222, 5154.175331946945, 5216.26972223889, 4172.248995180836, 4190.28765109932, 4212.873613791591, 4262.445882035316, 4263.914721705549, 1359.9048066635241, 1374.8006539051783, 1376.126159888458, 1398.8391334996402, 1404.368034384531, 775.3758224629115, 876.5660997806127, 949.8622699708642, 968.8692026576322, 991.476864714422, 5801.53587712982, 5922.940770592325, 5962.2249107615735, 5993.0, 6014.127951447103, 941.6751220828803, 967.8064387122604, 970.0784254957819, 1001.6994901529657, 1049.58541376156, 3856.9421458678144, 3985.601439856049, 4069.2268468990796, 4071.714979951375, 4093.9181216375505, 10696.465176741132, 11289.266197697123, 11309.434528273554, 11310.660680179608, 11313.547458101584, 10497.160227181806, 10748.247622666338, 10969.451527423756, 11194.0, 11230.55077796879, 8117.918820811835, 8326.625301861432, 8343.8331233375, 8345.582720863933, 8630.26642735362, 5754.597178197486, 5923.7926672566255, 5971.58198926071, 6029.939700603113, 6084.817455476343, 9896.059874311928, 9896.307258491837, 9903.405795652285, 9947.950260248652, 10112.494375281354, 10010.64962726301, 10112.64605738825, 10253.179745620482, 10290.948545257525, 10419.94790026045, 7999.800004999922, 8118.391120665856, 8139.0, 8145.551994640352, 8160.0, 8544.18830211129, 8653.826923461622, 8724.825503490021, 8740.956295218482, 8741.825163496567, 7392.815179620337, 7422.591757453392, 7584.810379740551, 7809.570473624005, 7886.72396466121, 7234.927650723425, 7236.457265704988, 7249.383802376857, 7795.4543181976715, 7811.882821757792, 6661.1007513985, 6745.797626071226, 6769.120014398097, 6770.593764374154, 7048.894266586108, 5235.738213089407, 5280.419153893145, 5316.6544174628525, 5340.706261155674, 5396.190571414162, 5328.520433161034, 5418.701971391612, 5440.455954404528, 5468.343798744176, 5500.9174862377895], "value": 4778.236146490569, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.31341607653496, 367.49285985340236, 370.33524822942826, 373.1306056887453, 378.17557724161315, 5243.475652434755, 5317.760700768465, 5374.543163831074, 5434.619576629632, 5469.398366179722, 5205.973970130153, 5308.362996440433, 5345.0, 5361.115415956372, 5368.62419630625, 4307.85841751936, 4308.6553075753945, 4310.267254566731, 4311.267084595611, 4318.9784051079705, 4201.201771663389, 4306.720063195898, 4308.676849236304, 4323.459567554056, 4327.437433133704, 4250.659947204216, 4266.0, 4270.274053410912, 4274.829006839723, 4283.978580107095, 4240.434537105053, 4247.277962746341, 4255.787210639455, 4261.083866968616, 4292.0, 3628.2017956049626, 3728.5339332583426, 3752.4746535485056, 3759.4360845873066, 3861.131245469747, 5184.351956005499, 5188.377394712621, 5192.480751924815, 5194.740262986873, 5218.973905130488, 4831.710097394168, 4836.903261934742, 4839.7338146401935, 4840.5159484051665, 4859.927101093486, 5406.324209473816, 5544.500994910478, 5584.984108581361, 5586.6871285247935, 5608.831735047955, 4115.238680844053, 4122.711410201287, 4140.15126898985, 4141.565135660742, 4143.6892233082635, 5714.314282286111, 5716.371199168088, 5729.083346664555, 5736.483716465496, 5755.194272801811, 4046.386621540947, 4079.4492743479846, 4156.334986402222, 4164.250434921743, 4184.309588917795, 4206.894827629334, 4218.472690913636, 4219.51475580306, 4223.458437670228, 4235.915281694411, 4765.285207218945, 4775.42694876617, 4786.473487916326, 4788.425388953278, 4792.544708252711, 5337.2794672719465, 5574.7353274744855, 5577.860553486195, 5599.832005039855, 5714.142878568165, 1361.3125371687236, 1385.1342910680823, 1410.4076287959083, 1431.7566013777682, 1433.7490939085826, 977.4037836919473, 1001.1740314240781, 1005.7234260578253, 1006.3861044762689, 1011.3780025284398, 5291.894162116813, 5294.258803767476, 5306.416294207634, 5309.389420216725, 5312.973435132877, 5511.0, 5511.834644960658, 5512.476314750093, 5559.444055594408, 5573.331200255995, 5156.793728250828, 5217.217417387346, 5272.604554658339, 5296.682199068068, 5321.521063104339, 2910.4969726035592, 2928.3996780659913, 2929.8974535891566, 2932.912012639624, 2943.7939344245697, 5722.54219662426, 5738.68437235956, 5739.33559267816, 5768.278965129359, 5769.432602491935, 5466.617336786467, 5597.0261114608265, 5650.406707295757, 5654.943450565524, 5682.318121825325, 5699.458551437607, 5721.768510280994, 5742.105105315236, 5744.798932037359, 5750.66461452365, 5238.476152384731, 5478.616496845342, 5485.546330222414, 5495.318136848241, 5532.2254884317035, 5407.351117865724, 5416.593755468434, 5436.320459942507, 5446.591505637172, 5459.553218397048, 5695.629784064023, 5709.001849352693, 5724.883647688661, 5739.053056245672, 5757.65454072757, 3946.9343277314388, 4097.610726980932, 4108.6713062954295, 4149.8340066397, 4186.895327616834, 5550.7779688812, 5803.158542011301, 5812.331581868139, 5829.854253643523, 5835.533157347487, 5532.28080349552, 5555.194561767523, 5575.553955683455, 5580.748866300945, 5587.832365029056, 5367.892642147057, 5656.953463609246, 5661.037623603915, 5670.914936276042, 5671.858203544944, 5512.972435137799, 5638.85902852432, 5645.40723224072, 5662.688552129673, 5697.544196464356, 5718.0, 5719.28508936383, 5736.68448235351, 5740.225069615549, 5744.885102297847, 5455.208994695826, 5597.832065037893, 5610.49505544503, 5612.849365880066, 5615.719214039364], "value": 4279.955070865465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.8594452998458, 794.6571984106855, 830.178123657579, 868.665563757953, 884.7390019944114, 14749.0, 15442.0, 15783.368665253376, 16236.350545978146, 16256.75614865778, 13189.472421103144, 13298.601041968732, 13304.93347533261, 13308.733825323488, 13335.733285334287, 700.5866538742141, 715.1989771455976, 838.0446291228005, 853.8335024670192, 855.9871601925976, 821.8520666280067, 827.2389401750374, 837.1001173738225, 850.2347886901807, 854.3336197765752, 885.4465958775766, 891.5096696816757, 893.0578239956843, 893.1247377569986, 893.2541327991136, 748.4611080022376, 755.920628334028, 878.1832895407236, 883.5273128876053, 886.2776836877956, 630.8391360203165, 634.5399585300667, 643.0964494884695, 654.5647144648802, 654.8264709851893, 14334.49829255971, 14392.568222953223, 14396.568102956824, 14402.639934001629, 14409.207493587848, 13216.273104979222, 13361.532346367927, 13515.121517101357, 13559.186448813103, 13602.0, 13049.54326598574, 13105.475780968749, 13186.67033324165, 13191.604251872459, 13424.664383390396, 4365.170617582649, 4369.956300437018, 4385.2106620808245, 4470.083632855257, 4483.977580112079, 63149.36850631481, 63723.0, 64258.0, 64424.677876610316, 65060.349396506375, 10913.672589822318, 11039.46581622474, 11220.326780393203, 11292.040176585082, 11361.829572556418, 20485.89757051235, 20664.0, 20668.276610318568, 20675.27636532744, 20725.89637051805, 7685.42359323053, 7796.961015194887, 7920.920790792018, 7927.722529711547, 8145.104038555754, 4555.886102847456, 4597.816087356468, 4677.555632214934, 4740.881477963078, 4921.237208232729, 3350.798952062884, 3411.2495251045225, 3446.155691855461, 3449.3273811606496, 3450.551428314303, 599.3766482857866, 616.5745635511482, 616.8087892753172, 619.5260625621403, 621.5431657731564, 630.5428564290852, 657.3328072006917, 698.5668885291152, 728.5956294256632, 754.6264599023518, 9446.244300455946, 9593.760155996157, 9687.70936871895, 10158.441285729208, 10185.439800810878, 633.9809805705836, 722.8337482378977, 731.4075598765018, 739.8964145019702, 740.4779630360583, 4667.509911459248, 4746.28805679144, 4752.6197904168275, 4757.26262429324, 4821.5178482152205, 53464.46535534673, 53661.731691341294, 86086.0, 96540.0, 99581.0, 65147.674261629334, 75779.0, 77461.6126919373, 78336.2166378329, 78741.60629196817, 69055.3094469059, 70468.64765676139, 72920.27079729135, 74965.25034749691, 75396.24603754003, 10297.3821570706, 10500.264981451224, 10679.412632305297, 10713.89286107129, 10725.410102444128, 46575.76712116418, 47167.76416117897, 47376.0, 47877.28184077311, 48599.0, 58058.41941580699, 62042.0693689604, 62510.0, 63379.36620633735, 63770.36229637644, 12693.174943628635, 12737.0, 12803.61589152327, 12956.093073484766, 13048.217106973612, 14002.36989335462, 14211.0, 14575.125492470486, 14720.411183552538, 14957.0, 13235.404406801525, 13593.0, 13980.720385592434, 14322.212278324376, 14488.565343039725, 13621.863781362455, 13673.179609223478, 13834.0, 14170.07894486855, 14310.513775797159, 13176.604701858576, 13430.127041742646, 13480.79778803299, 13572.0, 14253.429862805786, 11206.7758644825, 11373.83897059556, 11579.710507237387, 11580.478878450658, 11647.59233426826, 11614.88385116138, 11725.941370293434, 11727.941360293144, 11998.520059197883, 12014.879851201376], "value": 7339.917196205062, "unit": "score"}], "name": "dromaeo_css", "value": 7350.430228053602, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 114.0, 112.0, 112.0, 116.0, 112.0, 112.0, 113.0, 111.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 121.0, 167.0, 135.0, 126.0, 124.0, 121.0, 148.0, 122.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 102.0, 76.0, 75.0, 76.0, 77.0, 106.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 126.0, 82.0, 82.0, 82.0, 99.0, 82.0, 82.0, 119.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 84.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 49.0, 48.0, 48.0, 48.0, 49.0, 48.0, 50.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 60.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 82.0, 78.0, 76.0, 75.0, 75.0, 83.0, 82.0, 76.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 128.0, 123.0, 130.0, 122.0, 130.0, 122.0, 164.0, 134.0], "value": 132.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 153.0, 162.0, 154.0, 154.0, 155.0, 152.0, 155.0, 153.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 65.0, 61.0, 61.0, 62.0, 61.0, 58.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1463.9, "alertThreshold": 2.0}]} 10:09:03 INFO - Return code: 0 10:09:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:03 ERROR - # TBPL SUCCESS # 10:09:03 INFO - Running post-action listener: _resource_record_post_action 10:09:03 INFO - [mozharness: 2016-11-14 18:09:03.971551Z] Finished run-tests step (success) 10:09:03 INFO - Running post-run listener: _resource_record_post_run 10:09:04 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 366526464; Read time: 12; Write time: 321524 10:09:04 INFO - TinderboxPrint: CPU usage
13.0% 10:09:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:09:04 INFO - TinderboxPrint: I/O write bytes / time
366,526,464 / 321,524 10:09:04 INFO - TinderboxPrint: CPU idle
8,276.9 (87.0%) 10:09:04 INFO - TinderboxPrint: CPU user
1,189.7 (12.5%) 10:09:04 INFO - TinderboxPrint: Swap in / out
0 / 0 10:09:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107528192; Read time: 0; Write time: 126004 10:09:04 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 246411264; Read time: 0; Write time: 190736 10:09:04 INFO - Running post-run listener: _upload_blobber_files 10:09:04 INFO - Blob upload gear active. 10:09:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:04 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 10:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:05 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:05 INFO - (blobuploader) - INFO - Done attempting. 10:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:09:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:06 INFO - (blobuploader) - INFO - Done attempting. 10:09:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:09 INFO - (blobuploader) - INFO - Done attempting. 10:09:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:09 INFO - Return code: 0 10:09:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae339416c9b9b66fb777df765a4982785995dca554006b14a59980ebdcd588508f2fa69e94f162184605b0b71339876f6bd2dd2462164327c7c4d023acb1ac7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8559e725e6ab60f0482c617fc8ef2d2f6d9f0422976f82987bdf5a12e6b466efe645897d0eb160220ba8539fd95428636ee5fa9c63a9482731c1881acf0e2516", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d51ba3f76c58c876786f9be9b8246aa1d56a0685de96c015ce0362a4aa927ed9c9f10de4bcfadc3d76352826b66cd7de460117439d842af6d214a8da9ea33b3"} 10:09:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:09 INFO - Contents: 10:09:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae339416c9b9b66fb777df765a4982785995dca554006b14a59980ebdcd588508f2fa69e94f162184605b0b71339876f6bd2dd2462164327c7c4d023acb1ac7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8559e725e6ab60f0482c617fc8ef2d2f6d9f0422976f82987bdf5a12e6b466efe645897d0eb160220ba8539fd95428636ee5fa9c63a9482731c1881acf0e2516", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d51ba3f76c58c876786f9be9b8246aa1d56a0685de96c015ce0362a4aa927ed9c9f10de4bcfadc3d76352826b66cd7de460117439d842af6d214a8da9ea33b3"} 10:09:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:09 INFO - Copying logs to upload dir... 10:09:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.691873 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-11-14 10:09:09.124481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:09.128551) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae339416c9b9b66fb777df765a4982785995dca554006b14a59980ebdcd588508f2fa69e94f162184605b0b71339876f6bd2dd2462164327c7c4d023acb1ac7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8559e725e6ab60f0482c617fc8ef2d2f6d9f0422976f82987bdf5a12e6b466efe645897d0eb160220ba8539fd95428636ee5fa9c63a9482731c1881acf0e2516", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d51ba3f76c58c876786f9be9b8246aa1d56a0685de96c015ce0362a4aa927ed9c9f10de4bcfadc3d76352826b66cd7de460117439d842af6d214a8da9ea33b3"} build_url:https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011688 build_url: 'https://queue.taskcluster.net/v1/task/M5rldo1ERxmFyrJ2M3IHUg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae339416c9b9b66fb777df765a4982785995dca554006b14a59980ebdcd588508f2fa69e94f162184605b0b71339876f6bd2dd2462164327c7c4d023acb1ac7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8559e725e6ab60f0482c617fc8ef2d2f6d9f0422976f82987bdf5a12e6b466efe645897d0eb160220ba8539fd95428636ee5fa9c63a9482731c1881acf0e2516", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d51ba3f76c58c876786f9be9b8246aa1d56a0685de96c015ce0362a4aa927ed9c9f10de4bcfadc3d76352826b66cd7de460117439d842af6d214a8da9ea33b3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:09.154040) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:09.154394) ========= 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=c6927cf182745ad25b1d38b9000001fe-1479145713.772233-1078334260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:09.237431) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:09:09.237788) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:09:09.238104) ========= ========= Total master_lag: 0.17 =========