builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1476322568.27 results: success (0) buildid: 20161012182915 builduid: 80450c60655049f6b901e666535d46f9 revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.272737) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.273201) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.273478) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.288072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.288403) ========= 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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-12 18:36:08-- 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% 70.5M=0s 2016-10-12 18:36:08 (70.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075090 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.379113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.379647) ========= 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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017462 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.430645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:08.431002) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d68def1644c1849141ceaa31bc1b811becfbb8da --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d68def1644c1849141ceaa31bc1b811becfbb8da --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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-12 18:36:09,005 truncating revision to first 12 chars 2016-10-12 18:36:09,005 Setting DEBUG logging. 2016-10-12 18:36:09,005 attempt 1/10 2016-10-12 18:36:09,005 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d68def1644c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 18:36:09,563 unpacking tar archive at: fx-team-d68def1644c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.915575 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:09.359628) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:09.359957) ========= script_repo_revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:09.360332) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:09.360600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 18:36:09.370050) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-12 18:36:09.370509) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:36:10 INFO - MultiFileLogger online at 20161012 18:36:10 in /builds/slave/test 18:36:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:36:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:36:10 INFO - {'append_to_log': False, 18:36:10 INFO - 'base_work_dir': '/builds/slave/test', 18:36:10 INFO - 'blob_upload_branch': 'Fx-Team', 18:36:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:36:10 INFO - 'branch': 'Fx-Team', 18:36:10 INFO - 'buildbot_json_path': 'buildprops.json', 18:36:10 INFO - 'config_files': ('talos/linux_config.py',), 18:36:10 INFO - 'default_actions': ('clobber', 18:36:10 INFO - 'read-buildbot-config', 18:36:10 INFO - 'download-and-extract', 18:36:10 INFO - 'populate-webroot', 18:36:10 INFO - 'create-virtualenv', 18:36:10 INFO - 'install', 18:36:10 INFO - 'run-tests'), 18:36:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:36:10 INFO - 'download_minidump_stackwalk': True, 18:36:10 INFO - 'download_symbols': 'ondemand', 18:36:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:36:10 INFO - 'tooltool.py': '/tools/tooltool.py', 18:36:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:36:10 INFO - '/tools/misc-python/virtualenv.py')}, 18:36:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:36:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:36:10 INFO - 'installer_path': 'installer.exe', 18:36:10 INFO - 'log_level': 'info', 18:36:10 INFO - 'log_name': 'talos', 18:36:10 INFO - 'log_to_console': True, 18:36:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:36:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:36:10 INFO - 'opt_config_files': (), 18:36:10 INFO - 'pip_index': False, 18:36:10 INFO - 'sps_profile': False, 18:36:10 INFO - 'sps_profile_interval': 0, 18:36:10 INFO - 'suite': 'dromaeojs', 18:36:10 INFO - 'system_bits': '32', 18:36:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:36:10 INFO - 'title': 'talos-linux64-ix-028', 18:36:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:36:10 INFO - 'use_talos_json': True, 18:36:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:36:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:36:10 INFO - 'work_dir': 'build'} 18:36:10 INFO - [mozharness: 2016-10-13 01:36:10.041653Z] Running clobber step. 18:36:10 INFO - Running pre-action listener: _resource_record_pre_action 18:36:10 INFO - Running main action method: clobber 18:36:10 INFO - rmtree: /builds/slave/test/build 18:36:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:36:11 INFO - Running post-action listener: _resource_record_post_action 18:36:11 INFO - [mozharness: 2016-10-13 01:36:11.027171Z] Finished clobber step (success) 18:36:11 INFO - [mozharness: 2016-10-13 01:36:11.027269Z] Running read-buildbot-config step. 18:36:11 INFO - Running pre-action listener: _resource_record_pre_action 18:36:11 INFO - Running main action method: read_buildbot_config 18:36:11 INFO - Using buildbot properties: 18:36:11 INFO - { 18:36:11 INFO - "project": "", 18:36:11 INFO - "product": "firefox", 18:36:11 INFO - "script_repo_revision": "production", 18:36:11 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:36:11 INFO - "repository": "", 18:36:11 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:36:11 INFO - "buildid": "20161012182915", 18:36:11 INFO - "stage_platform": "linux64-pgo", 18:36:11 INFO - "basedir": "/builds/slave/test", 18:36:11 INFO - "buildnumber": 1121, 18:36:11 INFO - "platform": "ubuntu64_hw", 18:36:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:36:11 INFO - "slavebuilddir": "test", 18:36:11 INFO - "branch": "fx-team", 18:36:11 INFO - "revision": "d68def1644c1849141ceaa31bc1b811becfbb8da", 18:36:11 INFO - "slavename": "talos-linux64-ix-028", 18:36:11 INFO - "builduid": "80450c60655049f6b901e666535d46f9", 18:36:11 INFO - "repo_path": "integration/fx-team" 18:36:11 INFO - } 18:36:11 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 18:36:11 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 18:36:11 INFO - Running post-action listener: _resource_record_post_action 18:36:11 INFO - [mozharness: 2016-10-13 01:36:11.029631Z] Finished read-buildbot-config step (success) 18:36:11 INFO - [mozharness: 2016-10-13 01:36:11.029717Z] Running download-and-extract step. 18:36:11 INFO - Running pre-action listener: _resource_record_pre_action 18:36:11 INFO - Running main action method: download_and_extract 18:36:11 INFO - mkdir: /builds/slave/test/build/tests 18:36: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')]} 18:36:11 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:36:11 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:36:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:36:11 INFO - Downloaded 1361 bytes. 18:36:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 18:36:11 INFO - Using the following test package requirements: 18:36:11 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:11 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:36:11 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:36:11 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'jsshell-linux-x86_64.zip'], 18:36:11 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:36:11 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 18:36:11 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:36:11 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:36:11 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:36:11 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 18:36:11 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:36:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:36:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:36:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:11 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:36:11 INFO - Http code: 200 18:36:11 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 18:36:11 INFO - x-amz-cf-id: yGLW4haf4gc_0gp7s7Fhbyi2KXcl0qCmTrbNFR7vhNiQW-swYJKvgQ== 18:36:11 INFO - x-amz-replication-status: COMPLETED 18:36:11 INFO - x-amz-version-id: PPLZvMJMo_Fy0WkEaLUPfXFRrxRvhFxW 18:36:11 INFO - Expected file size: 22117649 18:36:11 INFO - Obtained file size: 22117649 18:36:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:36:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 18:36:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:36:15 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:36:15 INFO - Http code: 200 18:36:15 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 18:36:15 INFO - x-amz-cf-id: 9XFsX-0DatdLjoiw-MiX24YOI__7sWztBk_ED3xDUrOPDN3WhoGYxg== 18:36:15 INFO - x-amz-replication-status: COMPLETED 18:36:15 INFO - x-amz-version-id: PPLZvMJMo_Fy0WkEaLUPfXFRrxRvhFxW 18:36:15 INFO - Expected file size: 22117649 18:36:15 INFO - Obtained file size: 22117649 18:36:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 18:36:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:36:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:36:20 INFO - Http code: 200 18:36:20 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 18:36:20 INFO - x-amz-cf-id: GbyUfinOs-Ih0Oiu8vlWk6_CkiMrl3mM8jVTklwdEYL2lXZhbQgiNg== 18:36:20 INFO - x-amz-replication-status: COMPLETED 18:36:20 INFO - x-amz-version-id: qraT6K7K3tjQ3i_OnrbjB0M47MMZWnDw 18:36:20 INFO - Expected file size: 14409006 18:36:20 INFO - Obtained file size: 14409006 18:36:20 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')]} 18:36:20 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:36:20 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:36:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:36:21 INFO - Downloaded 59805831 bytes. 18:36:21 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:36:21 INFO - mkdir: /builds/slave/test/properties 18:36:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:36:21 INFO - Writing to file /builds/slave/test/properties/build_url 18:36:21 INFO - Contents: 18:36:21 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 18:36:21 INFO - Running post-action listener: _resource_record_post_action 18:36:21 INFO - Running post-action listener: set_extra_try_arguments 18:36:21 INFO - [mozharness: 2016-10-13 01:36:21.885656Z] Finished download-and-extract step (success) 18:36:21 INFO - [mozharness: 2016-10-13 01:36:21.885882Z] Running populate-webroot step. 18:36:21 INFO - Running pre-action listener: _resource_record_pre_action 18:36:21 INFO - Running main action method: populate_webroot 18:36:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:36:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:36:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:36:21 INFO - '--tppagecycles', 18:36:21 INFO - '7'], 18:36:21 INFO - 'tests': ['tsvgm']}}, 18:36:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:36:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:36:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:36:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:36:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['damp', 'tps']}, 18:36:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'tests': ['damp', 'tps']}, 18:36:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['dromaeo_dom']}, 18:36:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:36:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['basic_compositor_video']}, 18:36:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:36:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other-e10s': {'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['tsvgx', 18:36:21 INFO - 'tsvgr_opacity', 18:36:21 INFO - 'tart', 18:36:21 INFO - 'tscrollx', 18:36:21 INFO - 'cart']}, 18:36:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:36:21 INFO - 'tsvgr_opacity', 18:36:21 INFO - 'tart', 18:36:21 INFO - 'tscrollx', 18:36:21 INFO - 'cart']}, 18:36:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-e10s'], 18:36:21 INFO - 'tests': ['tp5o']}, 18:36:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'tests': ['tp5o']}, 18:36:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-e10s', 18:36:21 INFO - '--xperf_path', 18:36:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:21 INFO - 'tests': ['tp5n']}, 18:36:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:21 INFO - 'talos_options': ['--xperf_path', 18:36:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:21 INFO - 'tests': ['tp5n']}}, 18:36:21 INFO - 'talos.zip': {'path': '', 18:36:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:36:21 INFO - Running post-action listener: _resource_record_post_action 18:36:21 INFO - [mozharness: 2016-10-13 01:36:21.903710Z] Finished populate-webroot step (success) 18:36:21 INFO - [mozharness: 2016-10-13 01:36:21.903798Z] Running create-virtualenv step. 18:36:21 INFO - Running pre-action listener: _resource_record_pre_action 18:36:21 INFO - Running main action method: create_virtualenv 18:36:21 INFO - Creating virtualenv /builds/slave/test/build/venv 18:36:21 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 18:36:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:36:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:36:22 INFO - Using real prefix '/usr' 18:36:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:36:22 INFO - Installing distribute.............................................................................................................................................................................................done. 18:36:24 INFO - Installing pip.................done. 18:36:24 INFO - Return code: 0 18:36:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:36:24 INFO - Reading from file tmpfile_stdout 18:36:24 INFO - Output received: 18:36:24 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) 18:36:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:36:24 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')]} 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:24 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')]} 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:24 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:24 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 18:36:24 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 18:36:24 INFO - Using env: {'DISPLAY': ':0', 18:36:24 INFO - 'HOME': '/home/cltbld', 18:36:24 INFO - 'LANG': 'en_US.UTF-8', 18:36:24 INFO - 'LANGUAGE': 'en_US:en', 18:36:24 INFO - 'LOGNAME': 'cltbld', 18:36:24 INFO - 'MAIL': '/var/mail/cltbld', 18:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:24 INFO - 'MOZ_NO_REMOTE': '1', 18:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:24 INFO - 'NO_EM_RESTART': '1', 18:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:24 INFO - 'PWD': '/builds/slave/test', 18:36:24 INFO - 'SHELL': '/bin/bash', 18:36:24 INFO - 'SHLVL': '1', 18:36:24 INFO - 'TERM': 'linux', 18:36:24 INFO - 'TMOUT': '86400', 18:36:24 INFO - 'USER': 'cltbld', 18:36:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:24 INFO - '_': '/tools/buildbot/bin/python'} 18:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:24 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 18:36:24 INFO - Cleaning up... 18:36:24 INFO - Return code: 0 18:36:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:36:24 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')]} 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:24 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')]} 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:24 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:24 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 18:36:24 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 18:36:24 INFO - Using env: {'DISPLAY': ':0', 18:36:24 INFO - 'HOME': '/home/cltbld', 18:36:24 INFO - 'LANG': 'en_US.UTF-8', 18:36:24 INFO - 'LANGUAGE': 'en_US:en', 18:36:24 INFO - 'LOGNAME': 'cltbld', 18:36:24 INFO - 'MAIL': '/var/mail/cltbld', 18:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:24 INFO - 'MOZ_NO_REMOTE': '1', 18:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:24 INFO - 'NO_EM_RESTART': '1', 18:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:24 INFO - 'PWD': '/builds/slave/test', 18:36:24 INFO - 'SHELL': '/bin/bash', 18:36:24 INFO - 'SHLVL': '1', 18:36:24 INFO - 'TERM': 'linux', 18:36:24 INFO - 'TMOUT': '86400', 18:36:24 INFO - 'USER': 'cltbld', 18:36:24 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:24 INFO - '_': '/tools/buildbot/bin/python'} 18:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:25 INFO - Downloading/unpacking psutil>=3.1.1 18:36:25 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 18:36:25 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 18:36:25 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 18:36:25 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 18:36:25 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 18:36:25 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 18:36:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:36:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:27 INFO - Installing collected packages: psutil 18:36:27 INFO - Running setup.py install for psutil 18:36:27 INFO - building 'psutil._psutil_linux' extension 18:36:27 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 18:36:27 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 18:36:27 INFO - building 'psutil._psutil_posix' extension 18:36:27 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 18:36:27 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 18:36:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:27 INFO - Successfully installed psutil 18:36:27 INFO - Cleaning up... 18:36:27 INFO - Return code: 0 18:36:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:36:27 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')]} 18:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:27 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')]} 18:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:27 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:27 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 18:36:27 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 18:36:27 INFO - Using env: {'DISPLAY': ':0', 18:36:27 INFO - 'HOME': '/home/cltbld', 18:36:27 INFO - 'LANG': 'en_US.UTF-8', 18:36:27 INFO - 'LANGUAGE': 'en_US:en', 18:36:27 INFO - 'LOGNAME': 'cltbld', 18:36:27 INFO - 'MAIL': '/var/mail/cltbld', 18:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:27 INFO - 'MOZ_NO_REMOTE': '1', 18:36:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:27 INFO - 'NO_EM_RESTART': '1', 18:36:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:27 INFO - 'PWD': '/builds/slave/test', 18:36:27 INFO - 'SHELL': '/bin/bash', 18:36:27 INFO - 'SHLVL': '1', 18:36:27 INFO - 'TERM': 'linux', 18:36:27 INFO - 'TMOUT': '86400', 18:36:27 INFO - 'USER': 'cltbld', 18:36:27 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:27 INFO - '_': '/tools/buildbot/bin/python'} 18:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:36:27 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 18:36:27 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 18:36:27 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 18:36:27 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 18:36:27 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 18:36:27 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 18:36:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:36:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:36:29 INFO - Installing collected packages: mozsystemmonitor 18:36:29 INFO - Running setup.py install for mozsystemmonitor 18:36:30 INFO - Successfully installed mozsystemmonitor 18:36:30 INFO - Cleaning up... 18:36:30 INFO - Return code: 0 18:36:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:30 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:30 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 18:36:30 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 18:36:30 INFO - Using env: {'DISPLAY': ':0', 18:36:30 INFO - 'HOME': '/home/cltbld', 18:36:30 INFO - 'LANG': 'en_US.UTF-8', 18:36:30 INFO - 'LANGUAGE': 'en_US:en', 18:36:30 INFO - 'LOGNAME': 'cltbld', 18:36:30 INFO - 'MAIL': '/var/mail/cltbld', 18:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:30 INFO - 'MOZ_NO_REMOTE': '1', 18:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:30 INFO - 'NO_EM_RESTART': '1', 18:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:30 INFO - 'PWD': '/builds/slave/test', 18:36:30 INFO - 'SHELL': '/bin/bash', 18:36:30 INFO - 'SHLVL': '1', 18:36:30 INFO - 'TERM': 'linux', 18:36:30 INFO - 'TMOUT': '86400', 18:36:30 INFO - 'USER': 'cltbld', 18:36:30 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:30 INFO - '_': '/tools/buildbot/bin/python'} 18:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:30 INFO - Downloading/unpacking jsonschema==2.5.1 18:36:30 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 18:36:30 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 18:36:30 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 18:36:30 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 18:36:30 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 18:36:30 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 18:36:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:36:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 INFO - Downloading functools32-3.2.3-2.tar.gz 18:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:36:32 INFO - warning: no files found matching '*.txt' 18:36:32 INFO - no previously-included directories found matching 'build' 18:36:32 INFO - no previously-included directories found matching 'dist' 18:36:32 INFO - no previously-included directories found matching '.git*' 18:36:32 INFO - Installing collected packages: jsonschema, functools32 18:36:32 INFO - Running setup.py install for functools32 18:36:32 INFO - warning: no files found matching '*.txt' 18:36:32 INFO - no previously-included directories found matching 'build' 18:36:32 INFO - no previously-included directories found matching 'dist' 18:36:32 INFO - no previously-included directories found matching '.git*' 18:36:32 INFO - Successfully installed jsonschema functools32 18:36:32 INFO - Cleaning up... 18:36:32 INFO - Return code: 0 18:36:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:36:32 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')]} 18:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:32 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')]} 18:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:32 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:32 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 18:36:32 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 18:36:32 INFO - Using env: {'DISPLAY': ':0', 18:36:32 INFO - 'HOME': '/home/cltbld', 18:36:32 INFO - 'LANG': 'en_US.UTF-8', 18:36:32 INFO - 'LANGUAGE': 'en_US:en', 18:36:32 INFO - 'LOGNAME': 'cltbld', 18:36:32 INFO - 'MAIL': '/var/mail/cltbld', 18:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:32 INFO - 'MOZ_NO_REMOTE': '1', 18:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:32 INFO - 'NO_EM_RESTART': '1', 18:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:32 INFO - 'PWD': '/builds/slave/test', 18:36:32 INFO - 'SHELL': '/bin/bash', 18:36:32 INFO - 'SHLVL': '1', 18:36:32 INFO - 'TERM': 'linux', 18:36:32 INFO - 'TMOUT': '86400', 18:36:32 INFO - 'USER': 'cltbld', 18:36:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:32 INFO - '_': '/tools/buildbot/bin/python'} 18:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:36:32 INFO - Cleaning up... 18:36:32 INFO - Return code: 0 18:36:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:36:32 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')]} 18:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:32 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')]} 18:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:32 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:32 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 18:36:32 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 18:36:32 INFO - Using env: {'DISPLAY': ':0', 18:36:32 INFO - 'HOME': '/home/cltbld', 18:36:32 INFO - 'LANG': 'en_US.UTF-8', 18:36:32 INFO - 'LANGUAGE': 'en_US:en', 18:36:32 INFO - 'LOGNAME': 'cltbld', 18:36:32 INFO - 'MAIL': '/var/mail/cltbld', 18:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:32 INFO - 'MOZ_NO_REMOTE': '1', 18:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:32 INFO - 'NO_EM_RESTART': '1', 18:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:32 INFO - 'PWD': '/builds/slave/test', 18:36:32 INFO - 'SHELL': '/bin/bash', 18:36:32 INFO - 'SHLVL': '1', 18:36:32 INFO - 'TERM': 'linux', 18:36:32 INFO - 'TMOUT': '86400', 18:36:32 INFO - 'USER': 'cltbld', 18:36:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:32 INFO - '_': '/tools/buildbot/bin/python'} 18:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:33 INFO - Downloading/unpacking blobuploader==1.2.4 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:33 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 18:36:35 INFO - Downloading blobuploader-1.2.4.tar.gz 18:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:36:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:36:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 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 18:36:35 INFO - Downloading docopt-0.6.1.tar.gz 18:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:36:35 INFO - Installing collected packages: blobuploader, requests, docopt 18:36:35 INFO - Running setup.py install for blobuploader 18:36:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:36:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:36:35 INFO - Running setup.py install for requests 18:36:36 INFO - Running setup.py install for docopt 18:36:36 INFO - Successfully installed blobuploader requests docopt 18:36:36 INFO - Cleaning up... 18:36:36 INFO - Return code: 0 18:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:36 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')]} 18:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:36 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')]} 18:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:36 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:36 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 18:36:36 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 18:36:36 INFO - Using env: {'DISPLAY': ':0', 18:36:36 INFO - 'HOME': '/home/cltbld', 18:36:36 INFO - 'LANG': 'en_US.UTF-8', 18:36:36 INFO - 'LANGUAGE': 'en_US:en', 18:36:36 INFO - 'LOGNAME': 'cltbld', 18:36:36 INFO - 'MAIL': '/var/mail/cltbld', 18:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:36 INFO - 'MOZ_NO_REMOTE': '1', 18:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:36 INFO - 'NO_EM_RESTART': '1', 18:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:36 INFO - 'PWD': '/builds/slave/test', 18:36:36 INFO - 'SHELL': '/bin/bash', 18:36:36 INFO - 'SHLVL': '1', 18:36:36 INFO - 'TERM': 'linux', 18:36:36 INFO - 'TMOUT': '86400', 18:36:36 INFO - 'USER': 'cltbld', 18:36:36 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:36 INFO - '_': '/tools/buildbot/bin/python'} 18:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:36 INFO - Running setup.py (path:/tmp/pip-NS4s3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:36 INFO - Running setup.py (path:/tmp/pip-ZzBswu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:36 INFO - Running setup.py (path:/tmp/pip-0udPuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:36 INFO - Running setup.py (path:/tmp/pip-aeL0IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:36 INFO - Running setup.py (path:/tmp/pip-8EBBR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:37 INFO - Running setup.py (path:/tmp/pip-VPjdya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:37 INFO - Running setup.py (path:/tmp/pip-cJVcol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:37 INFO - Running setup.py (path:/tmp/pip-XFjvzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:37 INFO - Running setup.py (path:/tmp/pip-E0pXPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:37 INFO - Running setup.py (path:/tmp/pip-hVJ2pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:37 INFO - Running setup.py (path:/tmp/pip-yM03Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:37 INFO - Running setup.py (path:/tmp/pip-F05lt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:37 INFO - Running setup.py (path:/tmp/pip-s24DLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:37 INFO - Running setup.py (path:/tmp/pip-MDUvsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:37 INFO - Running setup.py (path:/tmp/pip-QNsw4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:37 INFO - Running setup.py (path:/tmp/pip-mvlzHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:37 INFO - Running setup.py (path:/tmp/pip-q51ZRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:36:37 INFO - Running setup.py install for manifestparser 18:36:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for mozcrash 18:36:38 INFO - Running setup.py install for mozdebug 18:36:38 INFO - Running setup.py install for mozdevice 18:36:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for mozfile 18:36:38 INFO - Running setup.py install for mozhttpd 18:36:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for mozinfo 18:36:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for mozInstall 18:36:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for mozleak 18:36:38 INFO - Running setup.py install for mozlog 18:36:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:36:38 INFO - Running setup.py install for moznetwork 18:36:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Running setup.py install for mozprocess 18:36:39 INFO - Running setup.py install for mozprofile 18:36:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Running setup.py install for mozrunner 18:36:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Running setup.py install for mozscreenshot 18:36:39 INFO - Running setup.py install for moztest 18:36:39 INFO - Running setup.py install for mozversion 18:36:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:36:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:36:39 INFO - Cleaning up... 18:36:39 INFO - Return code: 0 18:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:39 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')]} 18:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:39 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')]} 18:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:39 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:39 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 18:36:39 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 18:36:39 INFO - Using env: {'DISPLAY': ':0', 18:36:39 INFO - 'HOME': '/home/cltbld', 18:36:39 INFO - 'LANG': 'en_US.UTF-8', 18:36:39 INFO - 'LANGUAGE': 'en_US:en', 18:36:39 INFO - 'LOGNAME': 'cltbld', 18:36:39 INFO - 'MAIL': '/var/mail/cltbld', 18:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:39 INFO - 'MOZ_NO_REMOTE': '1', 18:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:39 INFO - 'NO_EM_RESTART': '1', 18:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:39 INFO - 'PWD': '/builds/slave/test', 18:36:39 INFO - 'SHELL': '/bin/bash', 18:36:39 INFO - 'SHLVL': '1', 18:36:39 INFO - 'TERM': 'linux', 18:36:39 INFO - 'TMOUT': '86400', 18:36:39 INFO - 'USER': 'cltbld', 18:36:39 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:39 INFO - '_': '/tools/buildbot/bin/python'} 18:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:39 INFO - Running setup.py (path:/tmp/pip-RHg0yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:40 INFO - Running setup.py (path:/tmp/pip-b_oPa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:40 INFO - Running setup.py (path:/tmp/pip-30q0Gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:40 INFO - Running setup.py (path:/tmp/pip-I1zbi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:40 INFO - Running setup.py (path:/tmp/pip-4oUGCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:40 INFO - Running setup.py (path:/tmp/pip-E3ri1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:40 INFO - Running setup.py (path:/tmp/pip-ZnycGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:40 INFO - Running setup.py (path:/tmp/pip-OKBDzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:40 INFO - Running setup.py (path:/tmp/pip-J0OlHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:40 INFO - Running setup.py (path:/tmp/pip-I6eOh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:40 INFO - Running setup.py (path:/tmp/pip-LgaKKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:40 INFO - Running setup.py (path:/tmp/pip-EowC1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:40 INFO - Running setup.py (path:/tmp/pip-1rjDnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:40 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)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:40 INFO - Running setup.py (path:/tmp/pip-SzJWDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:40 INFO - Running setup.py (path:/tmp/pip-Ep5vB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:41 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)) 18:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:41 INFO - Running setup.py (path:/tmp/pip-8TxbR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:41 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)) 18:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:41 INFO - Running setup.py (path:/tmp/pip-Hon4hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:41 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)) 18:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:36:41 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)) 18:36:41 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)) 18:36:41 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)) 18:36:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:41 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 18:36:43 INFO - Downloading blessings-1.6.tar.gz 18:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:36:43 INFO - Installing collected packages: blessings 18:36:43 INFO - Running setup.py install for blessings 18:36:43 INFO - Successfully installed blessings 18:36:43 INFO - Cleaning up... 18:36:43 INFO - Return code: 0 18:36:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:36:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:43 INFO - Reading from file tmpfile_stdout 18:36:43 INFO - Current package versions: 18:36:43 INFO - argparse == 1.2.1 18:36:43 INFO - blessings == 1.6 18:36:43 INFO - blobuploader == 1.2.4 18:36:43 INFO - docopt == 0.6.1 18:36:43 INFO - functools32 == 3.2.3-2 18:36:43 INFO - jsonschema == 2.5.1 18:36:43 INFO - manifestparser == 1.1 18:36:43 INFO - mozInstall == 1.12 18:36:43 INFO - mozcrash == 0.17 18:36:43 INFO - mozdebug == 0.1 18:36:43 INFO - mozdevice == 0.48 18:36:43 INFO - mozfile == 1.2 18:36:43 INFO - mozhttpd == 0.7 18:36:43 INFO - mozinfo == 0.9 18:36:43 INFO - mozleak == 0.1 18:36:43 INFO - mozlog == 3.4 18:36:43 INFO - moznetwork == 0.27 18:36:43 INFO - mozprocess == 0.23 18:36:43 INFO - mozprofile == 0.28 18:36:43 INFO - mozrunner == 6.12 18:36:43 INFO - mozscreenshot == 0.1 18:36:43 INFO - mozsystemmonitor == 0.3 18:36:43 INFO - moztest == 0.7 18:36:43 INFO - mozversion == 1.4 18:36:43 INFO - psutil == 3.1.1 18:36:43 INFO - requests == 1.2.3 18:36:43 INFO - wsgiref == 0.1.2 18:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:43 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')]} 18:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:43 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')]} 18:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:43 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:43 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 18:36:43 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 18:36:43 INFO - Using env: {'DISPLAY': ':0', 18:36:43 INFO - 'HOME': '/home/cltbld', 18:36:43 INFO - 'LANG': 'en_US.UTF-8', 18:36:43 INFO - 'LANGUAGE': 'en_US:en', 18:36:43 INFO - 'LOGNAME': 'cltbld', 18:36:43 INFO - 'MAIL': '/var/mail/cltbld', 18:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:43 INFO - 'MOZ_NO_REMOTE': '1', 18:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:43 INFO - 'NO_EM_RESTART': '1', 18:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:43 INFO - 'PWD': '/builds/slave/test', 18:36:43 INFO - 'SHELL': '/bin/bash', 18:36:43 INFO - 'SHLVL': '1', 18:36:43 INFO - 'TERM': 'linux', 18:36:43 INFO - 'TMOUT': '86400', 18:36:43 INFO - 'USER': 'cltbld', 18:36:43 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:43 INFO - '_': '/tools/buildbot/bin/python'} 18:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 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)) 18:36:43 INFO - Cleaning up... 18:36:43 INFO - Return code: 0 18:36:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:36:43 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')]} 18:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:43 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')]} 18:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:43 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 0x7f05a32b4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f77790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, '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': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', '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 18:36:43 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 18:36:43 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 18:36:43 INFO - Using env: {'DISPLAY': ':0', 18:36:43 INFO - 'HOME': '/home/cltbld', 18:36:43 INFO - 'LANG': 'en_US.UTF-8', 18:36:43 INFO - 'LANGUAGE': 'en_US:en', 18:36:43 INFO - 'LOGNAME': 'cltbld', 18:36:43 INFO - 'MAIL': '/var/mail/cltbld', 18:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:43 INFO - 'MOZ_NO_REMOTE': '1', 18:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:43 INFO - 'NO_EM_RESTART': '1', 18:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:43 INFO - 'PWD': '/builds/slave/test', 18:36:43 INFO - 'SHELL': '/bin/bash', 18:36:43 INFO - 'SHLVL': '1', 18:36:43 INFO - 'TERM': 'linux', 18:36:43 INFO - 'TMOUT': '86400', 18:36:43 INFO - 'USER': 'cltbld', 18:36:43 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:43 INFO - '_': '/tools/buildbot/bin/python'} 18:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:36:44 INFO - Cleaning up... 18:36:44 INFO - Return code: 0 18:36:44 INFO - Running post-action listener: _resource_record_post_action 18:36:44 INFO - Running post-action listener: _start_resource_monitoring 18:36:44 INFO - Starting resource monitoring. 18:36:44 INFO - [mozharness: 2016-10-13 01:36:44.086086Z] Finished create-virtualenv step (success) 18:36:44 INFO - [mozharness: 2016-10-13 01:36:44.086557Z] Running install step. 18:36:44 INFO - Running pre-action listener: _resource_record_pre_action 18:36:44 INFO - Running main action method: install 18:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:44 INFO - Reading from file tmpfile_stdout 18:36:44 INFO - Detecting whether we're running mozinstall >=1.0... 18:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:36:44 INFO - Reading from file tmpfile_stdout 18:36:44 INFO - Output received: 18:36:44 INFO - Usage: mozinstall [options] installer 18:36:44 INFO - Options: 18:36:44 INFO - -h, --help show this help message and exit 18:36:44 INFO - -d DEST, --destination=DEST 18:36:44 INFO - Directory to install application into. [default: 18:36:44 INFO - "/builds/slave/test"] 18:36:44 INFO - --app=APP Application being installed. [default: firefox] 18:36:44 INFO - mkdir: /builds/slave/test/build/application 18:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:37:01 INFO - Reading from file tmpfile_stdout 18:37:01 INFO - Output received: 18:37:01 INFO - /builds/slave/test/build/application/firefox/firefox 18:37:01 INFO - Running post-action listener: _resource_record_post_action 18:37:01 INFO - [mozharness: 2016-10-13 01:37:01.647512Z] Finished install step (success) 18:37:01 INFO - [mozharness: 2016-10-13 01:37:01.647759Z] Running run-tests step. 18:37:01 INFO - Running pre-action listener: _resource_record_pre_action 18:37:01 INFO - Running main action method: run_tests 18:37:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:37:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:37:01 INFO - Python 2.7.3 18:37:01 INFO - Return code: 0 18:37:01 INFO - grabbing minidump binary from tooltool 18:37:01 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')]} 18:37:01 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 0x1f74930>, '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 0x1f75830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f75cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:37:01 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 18:37:01 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 18:37:01 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 18:37:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:37:01 INFO - Return code: 0 18:37:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:37:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:37:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:37:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:37:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:37:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:37:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 18:37:01 INFO - Using env: {'DISPLAY': ':0', 18:37:01 INFO - 'HOME': '/home/cltbld', 18:37:01 INFO - 'LANG': 'en_US.UTF-8', 18:37:01 INFO - 'LANGUAGE': 'en_US:en', 18:37:01 INFO - 'LOGNAME': 'cltbld', 18:37:01 INFO - 'MAIL': '/var/mail/cltbld', 18:37:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:01 INFO - 'MOZ_NO_REMOTE': '1', 18:37:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:01 INFO - 'NO_EM_RESTART': '1', 18:37:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:01 INFO - 'PWD': '/builds/slave/test', 18:37:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:01 INFO - 'SHELL': '/bin/bash', 18:37:01 INFO - 'SHLVL': '1', 18:37:01 INFO - 'TERM': 'linux', 18:37:01 INFO - 'TMOUT': '86400', 18:37:01 INFO - 'USER': 'cltbld', 18:37:01 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214', 18:37:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:01 INFO - '_': '/tools/buildbot/bin/python'} 18:37:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:37:01 INFO - mozversion application_buildid: 20161012153001 18:37:01 INFO - mozversion application_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 18:37:01 INFO - mozversion application_display_name: Nightly 18:37:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:37:01 INFO - mozversion application_name: Firefox 18:37:01 INFO - mozversion application_remotingname: firefox 18:37:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:37:01 INFO - mozversion application_vendor: Mozilla 18:37:01 INFO - mozversion application_version: 52.0a1 18:37:01 INFO - mozversion platform_buildid: 20161012153001 18:37:01 INFO - mozversion platform_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 18:37:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:37:01 INFO - mozversion platform_version: 52.0a1 18:37:01 INFO - using testdate: 1476322621 18:37:01 INFO - actual date: 1476322621 18:37:01 INFO - starting webserver on 'localhost:33995' 18:37:01 INFO - SUITE-START | Running 2 tests 18:37:01 INFO - TEST-START | dromaeo_css 18:37:01 INFO - operating with platform_type : linux_ 18:37:01 INFO - Initialising browser for dromaeo_css test... 18:37:01 INFO - TEST-INFO | started process 3673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVPPsZD/profile http://localhost:33995/getInfo.html) 18:37:05 INFO - PROCESS | 3673 | __metrics Screen width/height:1600/1200 18:37:05 INFO - PROCESS | 3673 | colorDepth:24 18:37:05 INFO - PROCESS | 3673 | Browser inner width/height: 1024/697 18:37:05 INFO - PROCESS | 3673 | __metrics 18:37:06 INFO - PROCESS | 3673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:06 INFO - PROCESS | 3673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:07 INFO - PROCESS | 3673 | console.error: PushService: 18:37:07 INFO - PROCESS | 3673 | stateChangeProcessEnqueue: Error transitioning state 18:37:07 INFO - PROCESS | 3673 | UnknownError 18:37:07 INFO - TEST-INFO | 3673: exit 0 18:37:07 INFO - Browser initialized. 18:37:07 INFO - Running cycle 1/1 for dromaeo_css test... 18:37:07 INFO - TEST-INFO | started process 3759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVPPsZD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:20 INFO - PROCESS | 3759 | RSS: Main: 172892160 18:37:20 INFO - PROCESS | 3759 | 18:38:11 INFO - PROCESS | 3759 | 1476322691628 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] 18:40:22 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33995/tests/dromaeo/cssquery-ext.html) 18:40:23 INFO - PROCESS | 3759 | RSS: Main: 330485760 18:40:23 INFO - PROCESS | 3759 | 18:43:26 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-ext.html (next: http://localhost:33995/tests/dromaeo/cssquery-jquery.html) 18:43:27 INFO - PROCESS | 3759 | RSS: Main: 281280512 18:43:27 INFO - PROCESS | 3759 | 18:46:29 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33995/tests/dromaeo/cssquery-mootools.html) 18:46:29 INFO - PROCESS | 3759 | RSS: Main: 215080960 18:46:29 INFO - PROCESS | 3759 | 18:49:31 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33995/tests/dromaeo/cssquery-prototype.html) 18:49:32 INFO - PROCESS | 3759 | RSS: Main: 252055552 18:49:32 INFO - PROCESS | 3759 | 18:52:34 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33995/tests/dromaeo/cssquery-yui.html) 18:52:35 INFO - PROCESS | 3759 | RSS: Main: 216895488 18:52:35 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | Cycle 1(1): loaded http://localhost:33995/tests/dromaeo/cssquery-yui.html (next: http://localhost:33995/tests/dromaeo/cssquery-dojo.html) 18:55:38 INFO - PROCESS | 3759 | RSS: Main: 309694464 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | __start_tp_report 18:55:38 INFO - PROCESS | 3759 | _x_x_mozilla_page_load 18:55:38 INFO - PROCESS | 3759 | _x_x_mozilla_page_load_details 18:55:38 INFO - PROCESS | 3759 | |i|pagename|runs| 18:55:38 INFO - PROCESS | 3759 | |0;dojo.html;2927.5462303342974;2946.720061594148;2960.126762605031;2973.494505933991;2999.820010799352;5006.724630145341;5091.821786237482;5105.285260063585;5109.738519681604;5145.485451454852;5066.695998240109;5132.153194722876;5139.229115632657;5164.122099243138;5189.948100518994;4704.223803072489;4731.597814185799;4788.162071637467;4806.7115973041555;4839.05638400512;4881.4630390657;4907.435644900828;4958.752062396884;4965.106280869442;5018.422881368653;4687.062587482517;4843.443004054544;4844.031193761261;4863.635227357945;4958.8512344629535;4733.668643194977;4752.881177970544;4802.039592081563;4804.015176888729;4804.255340422205;4781.904361912777;4782.880427989328;4790.113828941622;4833.564979151858;4838.346823178861;5225.555827754645;5236.29310043147;5272;5276.92084618731;5285.947140528584;5424.945750542483;5472.452754724535;5480.479354461359;5579.2746942897165;5613.270274864282;5680.914786278209;5707.74315155819;5732.770689172428;5743.396943320975;5746.310442746855;5282.815101471469;5318.308619879391;5318.813841515605;5320;5321.095413779667;5787.942120578825;5797.043487824546;5798.739056742456;5801.071828507503;5801.216835727123;5322.574194064466;5383.7846486140115;5384.138537833927;5447.291852059287;5447.319085114361;5464.726763661801;5516.172574113915;5521.199426083276;5521.723913804293;5522;5497.147942068986;5503.174523821459;5508.338999320107;5512.5589952803675;5515.145152501369;5515.172724091419;5522.6134170608475;5526.143447765603;5527.50252477279;5529.723513824292;4275.743455392686;4282.443282373271;4282.6359759420175;4284.078923031563;4285;3804.8668296609485;3810.48558444612;3815.6947444204397;3818.7326887117633;3820.102275965146;5310.097283461743;5315.760790764425;5329.467053294639;5379.569634429236;5386.461353864583;5671.17767923657;5680.857978550486;5682.687452190086;5690.516306113944;5752.654840709487;5223.895522089537;5269.446708095671;5274.894502109936;5335.039692855245;5384.596155288369;4899.289603007615;4899.975500122548;4924.4583095859425;4947.604191664658;4958.925616115761;5865.442782935615;5874.559408044413;5878.882422351615;5881.735321910524;5884.735186916513;5520.337559492726;5577.609567330248;5708.5147762439;5714.05718056516;5782.797602084075;5714.342850572071;5724.79963201286;5725.398833122462;5791.160281759206;5804.506616937439;5472.890542189054;5475.315585551806;5552.861178470571;5554.222408862682;5559.360673522435;5399.78400863961;5648.293963254593;5671.291088613923;5674.829755107354;5678.0915053591225;5321.5476684482255;5498.80754173602;5540.916886246629;5568.415316391883;5576.804811831566;5521.834344969658;5564.972175139098;5567.580267031881;5570.777168913361;5572;5494.670319780826;5495.835124946258;5503.449655034625;5509.090999984957;5563.610547261815;5251.894962100813;5259.605529585373;5312.442193569774;5477.233187353853;5542.3626282977975;5533.695646739468;5543.9168412474655;5549.139883318092;5552.750126244409;5556.416576259435 18:55:38 INFO - PROCESS | 3759 | |1;ext.html;552.7393109971478;592.6592209479549;607.7903123422418;613.8219916224296;615.8121772859276;31199.68800311996;43592.3461148083;44554.55445445544;44601.77699111504;44627.33059004109;45902;46203.76898115513;46313.53686463134;46371.30443043357;46406.30390544136;7894.7236846710375;8129.715459958903;8701.65193392265;8856.114388561156;8858.512781796999;10356.84464733026;10614.203934704929;10616.628418005372;10952;10954.671359859218;10678.305910115856;10763.13894888411;10764.300320479182;10773.892261077406;10775.407352595605;10613.469326533681;10672.893271067267;10674.519646615921;10687.519061642206;10693.518791654395;10515.84226236607;10596.523156457977;10598;10615.203859710553;10661.893381066167;42288.365674514906;42624.78687606573;42641.1471770566;42676.57323426757;42753;3476.617572067071;3557.62644922282;3593.7125029997537;3757.513786650642;4057.1885622875543;31749.523757143663;31992.20019499508;32274.6772532274;32428.513572296433;32601;4184.769837658928;4355.128974205171;4364.432623758923;4402.823887044515;4504.864854054384;339522;339897;340533;342399.9999999975;342846;59117.40882591119;59468.40531594715;59881.40118598758;59885;59910;61930.380696192464;62619;62635.6868215656;62665.686671567266;62687.37312626906;15896.364145434285;16064.036157830335;16096.356145754273;16096.597585060234;16126.838731612768;11404.429778511041;11693.76612467763;11695.298282103102;11976.28142311464;12980.35098245084;15556.377744890304;16973.236204370834;17311.220995055253;17458.30166793314;18153.27386904509;5880.764769409176;5963.135345374896;6027.517798576104;6050.42520960508;6067.271927368744;895.1987970766128;986.3835103060587;989.8614194012844;1053.4153544782566;1076.39721755817;30193.69806301909;31065;31245.53131703026;31817.681823181472;31865.522017169762;1041.0526420957044;1052.5368837711383;1054.7204990677476;1101.1521128730833;1122.2761318949233;15789.842101578837;15931.920340398225;16180.676386472205;16563.310463378817;16941.237644306035;467356;472038;472200;472558;472873;57097.429025710044;57125;57296.70813167427;57495.71252143796;57807.4219257802;1182.9171957963033;1241.1684171605045;1297.7534268489146;1304.1197191895433;1343.1538130977422;30543.694563054087;31659.683403166597;31994.840025800655;32682.673173267962;32807.83596082004;49934.250986235966;50583.241251381995;50847.745761270955;51167.744161278955;51184.74407628087;352737;355161;355670;356215;356621;27259.72740272572;28255.434891301647;28268.293292667848;29257.09828861117;29619;47035.764821175675;47132.52867471419;47182.76408617935;48079.519204807504;48757;39620.603793961694;39727.80136099301;40150.79924600358;40215.396769047904;41701;39627.2074558513;39789.40315895206;39891.202175956896;41248.1750364985;42167.789161053995;39770.80114599408;40301;41695;41761;41774.58225417707;31798.205044874063;35151;35307.82346088253;36008.4598731014;36198.45702314415;27538.449231015667;27581;27981;28009.57985630258;28118.71881281161 18:55:38 INFO - PROCESS | 3759 | |2;jquery.html;375.06982682946307;379.2225936829499;395.81198930508015;399.6722687396335;417.9644730197933;13610.903568271824;15308;16902.408415705453;18297.725534116966;18407.447776566725;18051.458456246302;18180.636387272247;18280.634387312246;18438.446846594623;18772.71840922387;5649.548036157097;5892.558058145635;5940.792072277472;5955.106733989915;5957.255343082114;4814.446338671045;5821.970890145543;5852.824415267528;5857.857717745042;5886.587938844283;5641.210230567724;5774.047282198432;5833.270841144857;5838.124281357788;5843.152742852284;5751.827445176651;5784.30588329399;5835.212246346731;5836.795712150097;5840.06558950568;4995.925061124086;5062.2406639004075;5232.162853943389;5239.13554263546;5239.685618862881;22385.888070559544;22510.099596016138;22576.88711556448;22670.773292267197;22830.086796528118;2358.8230882683865;2437.7562243775483;2476.3561474016706;2801.817881837674;3037.8329191894436;13873.791893121612;14039.578812635636;14210.218437985906;14691.118532887955;14977.251137443085;1847.5381154711322;1925.8748181368305;1927.6915693488975;1947.8880558236126;1957.4029920874127;79047;79915.60042199868;80381;81928.18071819325;82237;10311.381317120997;10351;10372.740681483023;10631.255812093234;10666.573337066431;14917;14935.925320373328;14939.103653780809;14947.177905215318;14948.252587370587;8121.390895682729;8230;8338.207870252316;8398.454100483572;8409.579521023925;7671.769846904602;7863.292303692695;7928.365730741527;8071.636776344961;8140.511569305861;6239.469645080128;6465.127207826978;6517.338078789879;6536.379043990813;6549.2468366137555;1830.3410772122024;1875.36237679187;1935.9382257895297;1948.4503683897974;1966.3314473079222;846.34408333542;914.6844338703137;919.8620206968874;1034.8602938603342;1036.5387402605918;18808.529786755167;19672.311469098513;19848.40454786359;19955.700664490043;20032.399028029184;987.8765154355706;994.080475560104;1008.5612758449951;1012.1599072769584;1023.22746326523;10448.40270281074;10891.782164356828;11085;11160.665180044612;11271.211015228855;209056;411742;420172;424975;426883;77842.6107869457;83543;83596;84101.15898841056;84430.57784711037;984.9704508864888;1001.8797744270735;1028.28534168752;1031.5615863258085;1031.7575369788094;16299.699348090973;16569.337226510805;17100.65798684044;17150.228239728993;17301.134943252535;38507.42238866475;38912.2217555653;38973;39675.404868927566;41206.793966029974;50905;51815.74092129666;51917.740411299215;52090.73954630203;55019;8236.958815205886;8356.122607126408;8406.537640429835;8740.344474164336;8827.911720882708;12774.23354598727;13184.670383240496;13299.041150619103;13460.798088028883;13972.650683732989;11659.358735269303;11733.471993760131;11856.881431185579;11994.64016079519;12418.130730848754;11394.772104558027;11496.54013839437;11748.471318790504;11788.882111179122;11847.703807404883;10694.304870183412;11429.828552571884;11482.253653512495;11897.167198296036;12042.976347010252;7322.63386830667;7661.88507172404;8476.830463390821;8573.957130214309;8796.120387961153;10617.628383006559;11136.888631113909;11332.093432525213;11357.772844543228;11673.649790506299 18:55:38 INFO - PROCESS | 3759 | |3;mootools.html;1343.9798403023951;1481.851814818518;1512.6445285357938;1634.5341577650372;1745.7643218165545;7553.395728341733;8800.647974081037;9127.68053118141;9134.314926380515;9365.76585585359;9184.908150918489;9255.352125351228;9266;9266.860997085032;9270.119338662833;2291.46150654596;2315.895784689693;2318.1654604342425;2322.326525307667;2325.6860323856317;2261.9321420357414;2284.565932472825;2323.8721870297127;2324.8023917966993;2328.69726935499;2271.250487339174;2276.169198242638;2283.383486458655;2298.092253559843;2309.6535519672016;2229.565234779219;2238.40682219212;2243.6410174372104;2243.7083179186684;2248.134468229727;2221.577900198973;2242;2244.068711484732;2244.2033078257145;2249.0778780699834;8974;9083.09169083093;9174.311926605464;9184.632614695403;9277.67528136512;5032.924506132411;5103.055934652101;5149.0474262261605;5151.5106064923775;5153.510416510426;3215.324781795826;3309.933801323984;3322.010190828274;3399.4900764885465;3401.9659803402146;1128.5598616539555;1133.3256712256195;1139.3961200563715;1145.604766355606;1150.2925700694013;12991.675208119872;13041.86958130416;13066.542671006564;13085;13086.47654093835;4393.340998850198;4520.59314661682;4680.134175177603;4693.06138772247;4721.220998535272;10128.493575321205;10131.442770647685;10134;10136.138428233535;10138.695839124837;5371.892562148813;5372.059889519319;5372.301600791872;5382.058139825514;5388.703621300788;4251.893702657459;4319.913601728011;4467.285234362552;4855.635827312965;5000.924986125211;3735.402335626287;3740.9064773380337;3835.961640383616;3845.057960799622;3863.517060367454;1131.8415421840948;1142.2974870454611;1146.879577644352;1147.4262868565718;1153.4716500636523;851.535912927456;882.6778225947482;884.1423818895661;890.5903284489078;902.7652810269377;5499.587530935116;5514.420985796514;5578.191162281447;5672.773089076473;5780.306363236438;423.4706616729089;424.72605169665394;427.09882148666287;428.6592159233417;455.99088018240116;3670.6512881276235;3734.3091528067393;3764.1906989997283;3765.3975363942186;3806.600306967784;11274.830877536844;11702;11744.647660570197;11762.117841161948;11766;11471.942640286912;11664.88335116638;11703.180777345504;11717.76564468723;11730.70673233159;7482.3639990601405;7712.884306735516;8569.185927336894;8595.742127736177;8664.133586641285;5489;5525.640833345819;5543.805966791143;5627.296587926509;5645.576581756302;10047.849282260626;10172.728408948882;10238.747475555367;10298.22763292771;10432.947835261079;10409.27135100566;10656.307340022873;10766.784664306513;10777.51501182433;10849.403282819521;7521.135069466863;7730.188330225245;8533.829323413622;8724.214820666171;8733.912660873564;8469.23776860061;8777.824443511221;8810.427322224035;8951.239144672776;8995.325350598601;7380.655459669733;7857.135715071452;7970.242826931432;7975.800604984922;8089;7560.395168386626;7657.157712651715;7771.766846994599;7793.9220607793195;7842.921570784448;6997.790066298019;7117;7130.393916516947;7143.428525718034;7144;4724.267738500401;4873.634477414279;4887.36464259644;4943.77753001123;4980.352554167935;4826.975865120652;4943.060818444484;4996.150654388835;5082.4917508250355;5106.821261255838 18:55:38 INFO - PROCESS | 3759 | |4;prototype.html;320.5880443629936;322.2346926050631;328.64998776303236;331.6467961620874;341.660048251989;5128.461511541291;5280.1551751719635;5340.890348312414;5353.14349704047;5365.195220716894;5095.210242412432;5245.68525888446;5272.9981303552395;5293.808892999074;5311.681299122046;4178.832846686144;4192.7484350938885;4219.2405367033925;4227.154569086188;4252.8511502097435;3933.7443066200603;4097.508299004109;4211.642010429117;4225.218334608107;4249.107687385653;4080.73497215864;4099.24164029654;4103.5280942691525;4112.588741125878;4122.567130451304;4098.897527561805;4104.42538044674;4106.32245679461;4122.072533679928;4126.360414135814;3634.818259087035;3647.9817600912093;3674.926501469956;3677.5586929568353;3678;4995.8509542804995;5070.416902056274;5072.289879416885;5098.4136824265315;5108.693478391308;4641.350210970467;4663.766811659403;4664.836730714409;4678.461976872669;4689.484156742756;5441.29263195782;5450.618456708032;5452.4274951129955;5457.345118585756;5458.918116228259;3813.9427908581392;3917.3536366499493;3927.764334139932;3961.485006949107;3973.0861901762755;5599.692016939067;5639.915401268984;5655.5475561954945;5673.886522269531;5689.345725241609;4022.7127319258143;4058.127502586958;4063.923060388999;4075.2664520386024;4081.4082507821972;4017.8593749218635;4123.835046598102;4127.484064491939;4135.813888375031;4147.440095587057;4659.184642687516;4675.649326300473;4679.485256621769;4682.578567928903;4693.530646935279;5526.198701188386;5528.894221155785;5553.111502159716;5571.359293681197;5586.24585680936;1315.4343632238038;1334.1128149780482;1388.3201326394974;1402.2708191740237;1410.5768269519103;877.9429337093069;891.6032365597376;906.7667971558675;917.060013486182;929.1730359979623;4674.883127921829;4689.835855745101;4698.788554514987;4713.34013238147;4718.504557021532;4951.752412379366;4958.6776859504735;4961.404631444249;4962.702237865739;4963.255511673206;4479.484859241164;4729.858104256878;4735.479097299294;4744.620430365562;4834.292796034232;2596.6234895939983;2605.5310044191847;2609.739026097413;2619.986900065526;2622.7377262274003;4974.950250497521;4987.850364488999;5029;5033.295338652591;5118.974405128025;4852.975735121302;5024.899502009939;5031.622628302892;5036.532099271404;5052.520010598987;4997.67654509747;5047.747612619354;5056.393232812086;5056.951934466547;5061.4179369373;4954.925676114789;4978.2781496682055;4979.427365852974;4983.4518202996915;4996.825111121093;4608.170529304757;4873.562299121839;4886.975565122151;4896.531040687851;4898;5044.8234311798915;5049.798008079636;5050.595952323879;5073.670211436245;5084.847454576368;3684.465752465931;3695.3902606070774;3701.759385639925;3706.83319250629;3722.9627703722617;4793.664443488922;4928.088303663833;5001.64988450805;5001.949980500149;5055.924161137512;4791.6406269529225;4819.7349145795915;4826.975865120652;4864.889168652483;4872.269159626085;4824.34871292371;4825;4841.806327746851;4859.538343857328;4868.537488938545;4778.276382461963;4808.110499557593;4814.772233080682;4825.5174482551465;5088.7786931137;5572.247746554163;5595.972020140036;5602.13166959122;5616.634918730269;5619.409961953936;5445.455454454523;5463.972680136574;5493.862653433696;5504.394516603091;5505.339359276913 18:55:38 INFO - PROCESS | 3759 | |5;yui.html;700.7162099349765;716.9318914703102;727.076612701869;775.7944144801628;812.3825892321834;14831.332590033448;16298.918505407471;16472.917635411806;16503.33986640536;16514.50456486306;15023.549293521184;15152.84847151528;15187;15217.239138043109;15227;784.9018872640905;805.1586092533288;806.8466991271669;806.8951036365264;807.1807115777491;788.5938741548078;790.3203245209115;790.3282210121408;790.5809920741988;790.7113903425237;823.2138307915956;824.171707434028;824.2540500846713;824.6289169873581;826.0211649195722;817.6851912013917;817.8527864984301;818.2104269380018;818.5825229133155;819.1030821250721;605.4550904186224;605.8727667189895;606.0122001138117;606.2452246952528;606.6390497653889;12810.551630692975;12826.102172847905;12850.35748212595;12865;12870.871291287061;12420;12853.67865803353;12890.677733056655;13208.273544955024;13613.183209007491;11102.944485277603;11119.666410007712;11137.554497820078;11479.196456248066;11790;3729.3287208302495;3739;3762.8871133865755;3826.885193444173;3936.094698219397;75228.62385688037;76042;76273;76928.23071769267;78150.44968929178;9940.502974851228;9945.502724863727;9987.700368988943;10012.749681257881;10380.948095259475;20169.49576260576;20200.29298974529;20282.290119845733;20301.898490507454;20307.086181121886;7951.721689740947;8118;8548.74353769388;8733.170348816851;8776.385653004356;4588.26587745959;4691.179043667345;4829.44461386945;5055.418626857959;5099.770510327045;3433.8496603637564;3579.2125732338845;3581.9641803582153;3600.1179710970455;3604.675579197832;553.2752094755882;560.9971950140223;567.8523583868141;568.8293511946399;637.0698779781478;559.6166625861298;579.3453397660617;627.1564745417406;701.7894631610599;722.3823630795594;9055.864162037575;9113.407628504126;9135.177833994972;9524.428534287965;9785.706428807147;565.4599857136425;690.1752405874931;691.9584824910521;714.4820005496077;732.6593134192626;4491.73049617024;4700.200965835747;4770.784538958445;4781.952180478151;4845.152098382769;118658;119036.40481797709;122422.38788806;122427;125233;56888;63829.00331597277;64166.358336416975;66044;110957;57989.710051449474;60554;69081.30918690849;77601.83597246045;84466.5776671125;9280.164785169367;9854.310198286052;10016.749581260527;10125.949370253102;10148.847767283349;43083;44628.55371446244;50509.49490505048;53349.73325133349;54490.72754636202;46020.30969535393;62788.68605656942;63224;64278.357216427234;75101.62449187718;13493.906553928193;13576.932115339756;13615.455381784617;13734.107283026571;13929.164250145024;14734.557963260691;14893.255337233311;15278.77081843751;15366.846331536988;15657;12632.431540580512;13194.736105278032;13452.865471345161;13600.591982240549;13885.375158117708;12348.753024939631;12584.685382865502;12811.487540498276;13223.206607603575;13356;12991.480340786262;13208.537701180412;13424.328783560588;13655.112417692819;13920.443182272596;11414.771704565695;11675.182737208313;11741.823872642088;11742.530298787953;11973.700657483632;10362.481875906326;11250.649922009412;11282.605108821152;11830.822537662114;12007.159498834997 18:55:38 INFO - PROCESS | 3759 | __end_tp_report 18:55:38 INFO - PROCESS | 3759 | __start_cc_report 18:55:38 INFO - PROCESS | 3759 | _x_x_mozilla_cycle_collect,1542 18:55:38 INFO - PROCESS | 3759 | __end_cc_report 18:55:38 INFO - PROCESS | 3759 | __startTimestamp1476323738750__endTimestamp 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | ------- Summary: start ------- 18:55:38 INFO - PROCESS | 3759 | Number of tests: 6 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#0] dojo.html Cycles:165 Average:5216.83 Median:5399.78 stddev:597.71 (11.1%) stddev-sans-first:571.93 18:55:38 INFO - PROCESS | 3759 | Values: 2927.5 2946.7 2960.1 2973.5 2999.8 5006.7 5091.8 5105.3 5109.7 5145.5 5066.7 5132.2 5139.2 5164.1 5189.9 4704.2 4731.6 4788.2 4806.7 4839.1 4881.5 4907.4 4958.8 4965.1 5018.4 4687.1 4843.4 4844.0 4863.6 4958.9 4733.7 4752.9 4802.0 4804.0 4804.3 4781.9 4782.9 4790.1 4833.6 4838.3 5225.6 5236.3 5272.0 5276.9 5285.9 5424.9 5472.5 5480.5 5579.3 5613.3 5680.9 5707.7 5732.8 5743.4 5746.3 5282.8 5318.3 5318.8 5320.0 5321.1 5787.9 5797.0 5798.7 5801.1 5801.2 5322.6 5383.8 5384.1 5447.3 5447.3 5464.7 5516.2 5521.2 5521.7 5522.0 5497.1 5503.2 5508.3 5512.6 5515.1 5515.2 5522.6 5526.1 5527.5 5529.7 4275.7 4282.4 4282.6 4284.1 4285.0 3804.9 3810.5 3815.7 3818.7 3820.1 5310.1 5315.8 5329.5 5379.6 5386.5 5671.2 5680.9 5682.7 5690.5 5752.7 5223.9 5269.4 5274.9 5335.0 5384.6 4899.3 4900.0 4924.5 4947.6 4958.9 5865.4 5874.6 5878.9 5881.7 5884.7 5520.3 5577.6 5708.5 5714.1 5782.8 5714.3 5724.8 5725.4 5791.2 5804.5 5472.9 5475.3 5552.9 5554.2 5559.4 5399.8 5648.3 5671.3 5674.8 5678.1 5321.5 5498.8 5540.9 5568.4 5576.8 5521.8 5565.0 5567.6 5570.8 5572.0 5494.7 5495.8 5503.4 5509.1 5563.6 5251.9 5259.6 5312.4 5477.2 5542.4 5533.7 5543.9 5549.1 5552.8 5556.4 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#1] ext.html Cycles:180 Average:55980.59 Median:179886.31 stddev:103786.82 (57.7%) stddev-sans-first:103994.53 18:55:38 INFO - PROCESS | 3759 | Values: 552.7 592.7 607.8 613.8 615.8 31199.7 43592.3 44554.6 44601.8 44627.3 45902.0 46203.8 46313.5 46371.3 46406.3 7894.7 8129.7 8701.7 8856.1 8858.5 10356.8 10614.2 10616.6 10952.0 10954.7 10678.3 10763.1 10764.3 10773.9 10775.4 10613.5 10672.9 10674.5 10687.5 10693.5 10515.8 10596.5 10598.0 10615.2 10661.9 42288.4 42624.8 42641.1 42676.6 42753.0 3476.6 3557.6 3593.7 3757.5 4057.2 31749.5 31992.2 32274.7 32428.5 32601.0 4184.8 4355.1 4364.4 4402.8 4504.9 339522.0 339897.0 340533.0 342400.0 342846.0 59117.4 59468.4 59881.4 59885.0 59910.0 61930.4 62619.0 62635.7 62665.7 62687.4 15896.4 16064.0 16096.4 16096.6 16126.8 11404.4 11693.8 11695.3 11976.3 12980.4 15556.4 16973.2 17311.2 17458.3 18153.3 5880.8 5963.1 6027.5 6050.4 6067.3 895.2 986.4 989.9 1053.4 1076.4 30193.7 31065.0 31245.5 31817.7 31865.5 1041.1 1052.5 1054.7 1101.2 1122.3 15789.8 15931.9 16180.7 16563.3 16941.2 467356.0 472038.0 472200.0 472558.0 472873.0 57097.4 57125.0 57296.7 57495.7 57807.4 1182.9 1241.2 1297.8 1304.1 1343.2 30543.7 31659.7 31994.8 32682.7 32807.8 49934.3 50583.2 50847.7 51167.7 51184.7 352737.0 355161.0 355670.0 356215.0 356621.0 27259.7 28255.4 28268.3 29257.1 29619.0 47035.8 47132.5 47182.8 48079.5 48757.0 39620.6 39727.8 40150.8 40215.4 41701.0 39627.2 39789.4 39891.2 41248.2 42167.8 39770.8 40301.0 41695.0 41761.0 41774.6 31798.2 35151.0 35307.8 36008.5 36198.5 27538.4 27581.0 27981.0 28009.6 28118.7 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#2] jquery.html Cycles:180 Average:25434.20 Median:2398.29 stddev:64417.25 (2686.0%) stddev-sans-first:64570.48 18:55:38 INFO - PROCESS | 3759 | Values: 375.1 379.2 395.8 399.7 418.0 13610.9 15308.0 16902.4 18297.7 18407.4 18051.5 18180.6 18280.6 18438.4 18772.7 5649.5 5892.6 5940.8 5955.1 5957.3 4814.4 5822.0 5852.8 5857.9 5886.6 5641.2 5774.0 5833.3 5838.1 5843.2 5751.8 5784.3 5835.2 5836.8 5840.1 4995.9 5062.2 5232.2 5239.1 5239.7 22385.9 22510.1 22576.9 22670.8 22830.1 2358.8 2437.8 2476.4 2801.8 3037.8 13873.8 14039.6 14210.2 14691.1 14977.3 1847.5 1925.9 1927.7 1947.9 1957.4 79047.0 79915.6 80381.0 81928.2 82237.0 10311.4 10351.0 10372.7 10631.3 10666.6 14917.0 14935.9 14939.1 14947.2 14948.3 8121.4 8230.0 8338.2 8398.5 8409.6 7671.8 7863.3 7928.4 8071.6 8140.5 6239.5 6465.1 6517.3 6536.4 6549.2 1830.3 1875.4 1935.9 1948.5 1966.3 846.3 914.7 919.9 1034.9 1036.5 18808.5 19672.3 19848.4 19955.7 20032.4 987.9 994.1 1008.6 1012.2 1023.2 10448.4 10891.8 11085.0 11160.7 11271.2 209056.0 411742.0 420172.0 424975.0 426883.0 77842.6 83543.0 83596.0 84101.2 84430.6 985.0 1001.9 1028.3 1031.6 1031.8 16299.7 16569.3 17100.7 17150.2 17301.1 38507.4 38912.2 38973.0 39675.4 41206.8 50905.0 51815.7 51917.7 52090.7 55019.0 8237.0 8356.1 8406.5 8740.3 8827.9 12774.2 13184.7 13299.0 13460.8 13972.7 11659.4 11733.5 11856.9 11994.6 12418.1 11394.8 11496.5 11748.5 11788.9 11847.7 10694.3 11429.8 11482.3 11897.2 12043.0 7322.6 7661.9 8476.8 8574.0 8796.1 10617.6 11136.9 11332.1 11357.8 11673.6 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#3] mootools.html Cycles:180 Average:5852.41 Median:4356.63 stddev:3518.61 (80.8%) stddev-sans-first:3512.17 18:55:38 INFO - PROCESS | 3759 | Values: 1344.0 1481.9 1512.6 1634.5 1745.8 7553.4 8800.6 9127.7 9134.3 9365.8 9184.9 9255.4 9266.0 9266.9 9270.1 2291.5 2315.9 2318.2 2322.3 2325.7 2261.9 2284.6 2323.9 2324.8 2328.7 2271.3 2276.2 2283.4 2298.1 2309.7 2229.6 2238.4 2243.6 2243.7 2248.1 2221.6 2242.0 2244.1 2244.2 2249.1 8974.0 9083.1 9174.3 9184.6 9277.7 5032.9 5103.1 5149.0 5151.5 5153.5 3215.3 3309.9 3322.0 3399.5 3402.0 1128.6 1133.3 1139.4 1145.6 1150.3 12991.7 13041.9 13066.5 13085.0 13086.5 4393.3 4520.6 4680.1 4693.1 4721.2 10128.5 10131.4 10134.0 10136.1 10138.7 5371.9 5372.1 5372.3 5382.1 5388.7 4251.9 4319.9 4467.3 4855.6 5000.9 3735.4 3740.9 3836.0 3845.1 3863.5 1131.8 1142.3 1146.9 1147.4 1153.5 851.5 882.7 884.1 890.6 902.8 5499.6 5514.4 5578.2 5672.8 5780.3 423.5 424.7 427.1 428.7 456.0 3670.7 3734.3 3764.2 3765.4 3806.6 11274.8 11702.0 11744.6 11762.1 11766.0 11471.9 11664.9 11703.2 11717.8 11730.7 7482.4 7712.9 8569.2 8595.7 8664.1 5489.0 5525.6 5543.8 5627.3 5645.6 10047.8 10172.7 10238.7 10298.2 10432.9 10409.3 10656.3 10766.8 10777.5 10849.4 7521.1 7730.2 8533.8 8724.2 8733.9 8469.2 8777.8 8810.4 8951.2 8995.3 7380.7 7857.1 7970.2 7975.8 8089.0 7560.4 7657.2 7771.8 7793.9 7842.9 6997.8 7117.0 7130.4 7143.4 7144.0 4724.3 4873.6 4887.4 4943.8 4980.4 4827.0 4943.1 4996.2 5082.5 5106.8 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#4] prototype.html Cycles:180 Average:4381.70 Median:4838.05 stddev:1242.32 (25.7%) stddev-sans-first:1207.83 18:55:38 INFO - PROCESS | 3759 | Values: 320.6 322.2 328.6 331.6 341.7 5128.5 5280.2 5340.9 5353.1 5365.2 5095.2 5245.7 5273.0 5293.8 5311.7 4178.8 4192.7 4219.2 4227.2 4252.9 3933.7 4097.5 4211.6 4225.2 4249.1 4080.7 4099.2 4103.5 4112.6 4122.6 4098.9 4104.4 4106.3 4122.1 4126.4 3634.8 3648.0 3674.9 3677.6 3678.0 4995.9 5070.4 5072.3 5098.4 5108.7 4641.4 4663.8 4664.8 4678.5 4689.5 5441.3 5450.6 5452.4 5457.3 5458.9 3813.9 3917.4 3927.8 3961.5 3973.1 5599.7 5639.9 5655.5 5673.9 5689.3 4022.7 4058.1 4063.9 4075.3 4081.4 4017.9 4123.8 4127.5 4135.8 4147.4 4659.2 4675.6 4679.5 4682.6 4693.5 5526.2 5528.9 5553.1 5571.4 5586.2 1315.4 1334.1 1388.3 1402.3 1410.6 877.9 891.6 906.8 917.1 929.2 4674.9 4689.8 4698.8 4713.3 4718.5 4951.8 4958.7 4961.4 4962.7 4963.3 4479.5 4729.9 4735.5 4744.6 4834.3 2596.6 2605.5 2609.7 2620.0 2622.7 4975.0 4987.9 5029.0 5033.3 5119.0 4853.0 5024.9 5031.6 5036.5 5052.5 4997.7 5047.7 5056.4 5057.0 5061.4 4954.9 4978.3 4979.4 4983.5 4996.8 4608.2 4873.6 4887.0 4896.5 4898.0 5044.8 5049.8 5050.6 5073.7 5084.8 3684.5 3695.4 3701.8 3706.8 3723.0 4793.7 4928.1 5001.6 5001.9 5055.9 4791.6 4819.7 4827.0 4864.9 4872.3 4824.3 4825.0 4841.8 4859.5 4868.5 4778.3 4808.1 4814.8 4825.5 5088.8 5572.2 5596.0 5602.1 5616.6 5619.4 5445.5 5464.0 5493.9 5504.4 5505.3 18:55:38 INFO - PROCESS | 3759 | 18:55:38 INFO - PROCESS | 3759 | [#5] yui.html Cycles:180 Average:19257.37 Median:25355.74 stddev:27622.34 (108.9%) stddev-sans-first:27664.68 18:55:38 INFO - PROCESS | 3759 | Values: 700.7 716.9 727.1 775.8 812.4 14831.3 16298.9 16472.9 16503.3 16514.5 15023.5 15152.8 15187.0 15217.2 15227.0 784.9 805.2 806.8 806.9 807.2 788.6 790.3 790.3 790.6 790.7 823.2 824.2 824.3 824.6 826.0 817.7 817.9 818.2 818.6 819.1 605.5 605.9 606.0 606.2 606.6 12810.6 12826.1 12850.4 12865.0 12870.9 12420.0 12853.7 12890.7 13208.3 13613.2 11102.9 11119.7 11137.6 11479.2 11790.0 3729.3 3739.0 3762.9 3826.9 3936.1 75228.6 76042.0 76273.0 76928.2 78150.4 9940.5 9945.5 9987.7 10012.7 10380.9 20169.5 20200.3 20282.3 20301.9 20307.1 7951.7 8118.0 8548.7 8733.2 8776.4 4588.3 4691.2 4829.4 5055.4 5099.8 3433.8 3579.2 3582.0 3600.1 3604.7 553.3 561.0 567.9 568.8 637.1 559.6 579.3 627.2 701.8 722.4 9055.9 9113.4 9135.2 9524.4 9785.7 565.5 690.2 692.0 714.5 732.7 4491.7 4700.2 4770.8 4782.0 4845.2 118658.0 119036.4 122422.4 122427.0 125233.0 56888.0 63829.0 64166.4 66044.0 110957.0 57989.7 60554.0 69081.3 77601.8 84466.6 9280.2 9854.3 10016.7 10125.9 10148.8 43083.0 44628.6 50509.5 53349.7 54490.7 46020.3 62788.7 63224.0 64278.4 75101.6 13493.9 13576.9 13615.5 13734.1 13929.2 14734.6 14893.3 15278.8 15366.8 15657.0 12632.4 13194.7 13452.9 13600.6 13885.4 12348.8 12584.7 12811.5 13223.2 13356.0 12991.5 13208.5 13424.3 13655.1 13920.4 11414.8 11675.2 11741.8 11742.5 11973.7 10362.5 11250.6 11282.6 11830.8 12007.2 18:55:38 INFO - PROCESS | 3759 | -------- Summary: end -------- 18:55:38 INFO - PROCESS | 3759 | 18:55:39 INFO - TEST-INFO | 3759: exit 0 18:55:39 INFO - TEST-OK | dromaeo_css | took 1117744ms 18:55:39 INFO - TEST-START | kraken 18:55:39 INFO - operating with platform_type : linux_ 18:55:39 INFO - Initialising browser for kraken test... 18:55:39 INFO - TEST-INFO | started process 4067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg7ZNUJ/profile http://localhost:33995/getInfo.html) 18:55:43 INFO - PROCESS | 4067 | __metrics Screen width/height:1600/1200 18:55:43 INFO - PROCESS | 4067 | colorDepth:24 18:55:43 INFO - PROCESS | 4067 | Browser inner width/height: 1024/697 18:55:43 INFO - PROCESS | 4067 | __metrics 18:55:44 INFO - PROCESS | 4067 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:55:44 INFO - PROCESS | 4067 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:55:45 INFO - PROCESS | 4067 | console.error: PushService: 18:55:45 INFO - PROCESS | 4067 | stateChangeProcessEnqueue: Error transitioning state 18:55:45 INFO - PROCESS | 4067 | UnknownError 18:55:45 INFO - TEST-INFO | 4067: exit 0 18:55:45 INFO - Browser initialized. 18:55:45 INFO - Running cycle 1/1 for kraken test... 18:55:45 INFO - TEST-INFO | started process 4149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg7ZNUJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:55:57 INFO - PROCESS | 4149 | RSS: Main: 172752896 18:55:57 INFO - PROCESS | 4149 | 18:56:00 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33995/tests/kraken/driver.html?testName=audio-beat-detection) 18:56:01 INFO - PROCESS | 4149 | RSS: Main: 232116224 18:56:01 INFO - PROCESS | 4149 | 18:56:04 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33995/tests/kraken/driver.html?testName=audio-dft) 18:56:04 INFO - PROCESS | 4149 | RSS: Main: 216948736 18:56:04 INFO - PROCESS | 4149 | 18:56:07 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33995/tests/kraken/driver.html?testName=audio-fft) 18:56:08 INFO - PROCESS | 4149 | RSS: Main: 299151360 18:56:08 INFO - PROCESS | 4149 | 18:56:10 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33995/tests/kraken/driver.html?testName=audio-oscillator) 18:56:11 INFO - PROCESS | 4149 | RSS: Main: 207757312 18:56:11 INFO - PROCESS | 4149 | 18:56:13 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33995/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:56:13 INFO - PROCESS | 4149 | RSS: Main: 212865024 18:56:13 INFO - PROCESS | 4149 | 18:56:16 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33995/tests/kraken/driver.html?testName=imaging-darkroom) 18:56:17 INFO - PROCESS | 4149 | RSS: Main: 248745984 18:56:17 INFO - PROCESS | 4149 | 18:56:20 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33995/tests/kraken/driver.html?testName=imaging-desaturate) 18:56:21 INFO - PROCESS | 4149 | RSS: Main: 247857152 18:56:21 INFO - PROCESS | 4149 | 18:56:24 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33995/tests/kraken/driver.html?testName=json-parse-financial) 18:56:24 INFO - PROCESS | 4149 | RSS: Main: 247545856 18:56:24 INFO - PROCESS | 4149 | 18:56:26 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33995/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:56:27 INFO - PROCESS | 4149 | RSS: Main: 209416192 18:56:27 INFO - PROCESS | 4149 | 18:56:28 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:56:29 INFO - PROCESS | 4149 | RSS: Main: 217276416 18:56:29 INFO - PROCESS | 4149 | 18:56:31 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:56:32 INFO - PROCESS | 4149 | RSS: Main: 226656256 18:56:32 INFO - PROCESS | 4149 | 18:56:34 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:56:35 INFO - PROCESS | 4149 | RSS: Main: 230457344 18:56:35 INFO - PROCESS | 4149 | 18:56:38 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:56:38 INFO - PROCESS | 4149 | RSS: Main: 232431616 18:56:38 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | Cycle 1(1): loaded http://localhost:33995/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33995/tests/kraken/driver.html?testName=ai-astar) 18:56:40 INFO - PROCESS | 4149 | RSS: Main: 282894336 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | __start_tp_report 18:56:40 INFO - PROCESS | 4149 | _x_x_mozilla_page_load 18:56:40 INFO - PROCESS | 4149 | _x_x_mozilla_page_load_details 18:56:40 INFO - PROCESS | 4149 | |i|pagename|runs| 18:56:40 INFO - PROCESS | 4149 | |0;ai-astar;109;112;112;112;111;112;111;110;113;110 18:56:40 INFO - PROCESS | 4149 | |1;audio-beat-detection;146;123;121;169;122;124;129;126;167;129 18:56:40 INFO - PROCESS | 4149 | |2;audio-dft;161;161;161;161;160;160;161;161;162;160 18:56:40 INFO - PROCESS | 4149 | |3;audio-fft;74;76;76;111;84;77;76;75;101;76 18:56:40 INFO - PROCESS | 4149 | |4;audio-oscillator;82;83;108;82;82;84;102;83;82;114 18:56:40 INFO - PROCESS | 4149 | |5;imaging-gaussian-blur;117;117;117;116;117;117;118;117;116;117 18:56:40 INFO - PROCESS | 4149 | |6;imaging-darkroom;145;146;146;145;145;145;145;146;146;145 18:56:40 INFO - PROCESS | 4149 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;96;95 18:56:40 INFO - PROCESS | 4149 | |8;json-parse-financial;54;52;52;52;52;53;54;55;54;54 18:56:40 INFO - PROCESS | 4149 | |9;json-stringify-tinderbox;60;61;61;61;62;61;61;62;62;62 18:56:40 INFO - PROCESS | 4149 | |10;stanford-crypto-aes;79;85;78;79;80;82;79;84;81;78 18:56:40 INFO - PROCESS | 4149 | |11;stanford-crypto-ccm;136;138;133;132;139;130;138;135;181;137 18:56:40 INFO - PROCESS | 4149 | |12;stanford-crypto-pbkdf2;163;165;170;166;166;168;169;163;166;170 18:56:40 INFO - PROCESS | 4149 | |13;stanford-crypto-sha256-iterative;66;67;65;64;66;65;65;65;65;65 18:56:40 INFO - PROCESS | 4149 | __end_tp_report 18:56:40 INFO - PROCESS | 4149 | __start_cc_report 18:56:40 INFO - PROCESS | 4149 | _x_x_mozilla_cycle_collect,1670 18:56:40 INFO - PROCESS | 4149 | __end_cc_report 18:56:40 INFO - PROCESS | 4149 | __startTimestamp1476323800669__endTimestamp 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | ------- Summary: start ------- 18:56:40 INFO - PROCESS | 4149 | Number of tests: 14 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 18:56:40 INFO - PROCESS | 4149 | Values: 109.0 112.0 112.0 112.0 111.0 112.0 111.0 110.0 113.0 110.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:129.00 stddev:18.50 (14.3%) stddev-sans-first:19.24 18:56:40 INFO - PROCESS | 4149 | Values: 146.0 123.0 121.0 169.0 122.0 124.0 129.0 126.0 167.0 129.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 18:56:40 INFO - PROCESS | 4149 | Values: 161.0 161.0 161.0 161.0 160.0 160.0 161.0 161.0 162.0 160.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:12.84 (16.9%) stddev-sans-first:13.24 18:56:40 INFO - PROCESS | 4149 | Values: 74.0 76.0 76.0 111.0 84.0 77.0 76.0 75.0 101.0 76.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:12.62 (15.4%) stddev-sans-first:13.03 18:56:40 INFO - PROCESS | 4149 | Values: 82.0 83.0 108.0 82.0 82.0 84.0 102.0 83.0 82.0 114.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:56:40 INFO - PROCESS | 4149 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 118.0 117.0 116.0 117.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:56:40 INFO - PROCESS | 4149 | Values: 145.0 146.0 146.0 145.0 145.0 145.0 145.0 146.0 146.0 145.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:56:40 INFO - PROCESS | 4149 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:1.17 18:56:40 INFO - PROCESS | 4149 | Values: 54.0 52.0 52.0 52.0 52.0 53.0 54.0 55.0 54.0 54.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.50 stddev:0.67 (1.1%) stddev-sans-first:0.53 18:56:40 INFO - PROCESS | 4149 | Values: 60.0 61.0 61.0 61.0 62.0 61.0 61.0 62.0 62.0 62.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.50 stddev:2.46 (3.1%) stddev-sans-first:2.55 18:56:40 INFO - PROCESS | 4149 | Values: 79.0 85.0 78.0 79.0 80.0 82.0 79.0 84.0 81.0 78.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:137.50 stddev:14.73 (10.7%) stddev-sans-first:15.56 18:56:40 INFO - PROCESS | 4149 | Values: 136.0 138.0 133.0 132.0 139.0 130.0 138.0 135.0 181.0 137.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:167.00 stddev:2.59 (1.6%) stddev-sans-first:2.40 18:56:40 INFO - PROCESS | 4149 | Values: 163.0 165.0 170.0 166.0 166.0 168.0 169.0 163.0 166.0 170.0 18:56:40 INFO - PROCESS | 4149 | 18:56:40 INFO - PROCESS | 4149 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 18:56:40 INFO - PROCESS | 4149 | Values: 66.0 67.0 65.0 64.0 66.0 65.0 65.0 65.0 65.0 65.0 18:56:40 INFO - PROCESS | 4149 | -------- Summary: end -------- 18:56:40 INFO - PROCESS | 4149 | 18:56:41 INFO - TEST-INFO | 4149: exit 0 18:56:41 INFO - TEST-OK | kraken | took 62123ms 18:56:41 INFO - SUITE-END | took 1179s 18:56:41 INFO - Completed test suite (00:19:39) 18:56:41 INFO - javascript benchmark 18:56:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2927.5462303342974, 2946.720061594148, 2960.126762605031, 2973.494505933991, 2999.820010799352, 5006.724630145341, 5091.821786237482, 5105.285260063585, 5109.738519681604, 5145.485451454852, 5066.695998240109, 5132.153194722876, 5139.229115632657, 5164.122099243138, 5189.948100518994, 4704.223803072489, 4731.597814185799, 4788.162071637467, 4806.7115973041555, 4839.05638400512, 4881.4630390657, 4907.435644900828, 4958.752062396884, 4965.106280869442, 5018.422881368653, 4687.062587482517, 4843.443004054544, 4844.031193761261, 4863.635227357945, 4958.8512344629535, 4733.668643194977, 4752.881177970544, 4802.039592081563, 4804.015176888729, 4804.255340422205, 4781.904361912777, 4782.880427989328, 4790.113828941622, 4833.564979151858, 4838.346823178861, 5225.555827754645, 5236.29310043147, 5272.0, 5276.92084618731, 5285.947140528584, 5424.945750542483, 5472.452754724535, 5480.479354461359, 5579.2746942897165, 5613.270274864282, 5680.914786278209, 5707.74315155819, 5732.770689172428, 5743.396943320975, 5746.310442746855, 5282.815101471469, 5318.308619879391, 5318.813841515605, 5320.0, 5321.095413779667, 5787.942120578825, 5797.043487824546, 5798.739056742456, 5801.071828507503, 5801.216835727123, 5322.574194064466, 5383.7846486140115, 5384.138537833927, 5447.291852059287, 5447.319085114361, 5464.726763661801, 5516.172574113915, 5521.199426083276, 5521.723913804293, 5522.0, 5497.147942068986, 5503.174523821459, 5508.338999320107, 5512.5589952803675, 5515.145152501369, 5515.172724091419, 5522.6134170608475, 5526.143447765603, 5527.50252477279, 5529.723513824292, 4275.743455392686, 4282.443282373271, 4282.6359759420175, 4284.078923031563, 4285.0, 3804.8668296609485, 3810.48558444612, 3815.6947444204397, 3818.7326887117633, 3820.102275965146, 5310.097283461743, 5315.760790764425, 5329.467053294639, 5379.569634429236, 5386.461353864583, 5671.17767923657, 5680.857978550486, 5682.687452190086, 5690.516306113944, 5752.654840709487, 5223.895522089537, 5269.446708095671, 5274.894502109936, 5335.039692855245, 5384.596155288369, 4899.289603007615, 4899.975500122548, 4924.4583095859425, 4947.604191664658, 4958.925616115761, 5865.442782935615, 5874.559408044413, 5878.882422351615, 5881.735321910524, 5884.735186916513, 5520.337559492726, 5577.609567330248, 5708.5147762439, 5714.05718056516, 5782.797602084075, 5714.342850572071, 5724.79963201286, 5725.398833122462, 5791.160281759206, 5804.506616937439, 5472.890542189054, 5475.315585551806, 5552.861178470571, 5554.222408862682, 5559.360673522435, 5399.78400863961, 5648.293963254593, 5671.291088613923, 5674.829755107354, 5678.0915053591225, 5321.5476684482255, 5498.80754173602, 5540.916886246629, 5568.415316391883, 5576.804811831566, 5521.834344969658, 5564.972175139098, 5567.580267031881, 5570.777168913361, 5572.0, 5494.670319780826, 5495.835124946258, 5503.449655034625, 5509.090999984957, 5563.610547261815, 5251.894962100813, 5259.605529585373, 5312.442193569774, 5477.233187353853, 5542.3626282977975, 5533.695646739468, 5543.9168412474655, 5549.139883318092, 5552.750126244409, 5556.416576259435], "value": 5175.492860229666, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.7393109971478, 592.6592209479549, 607.7903123422418, 613.8219916224296, 615.8121772859276, 31199.68800311996, 43592.3461148083, 44554.55445445544, 44601.77699111504, 44627.33059004109, 45902.0, 46203.76898115513, 46313.53686463134, 46371.30443043357, 46406.30390544136, 7894.7236846710375, 8129.715459958903, 8701.65193392265, 8856.114388561156, 8858.512781796999, 10356.84464733026, 10614.203934704929, 10616.628418005372, 10952.0, 10954.671359859218, 10678.305910115856, 10763.13894888411, 10764.300320479182, 10773.892261077406, 10775.407352595605, 10613.469326533681, 10672.893271067267, 10674.519646615921, 10687.519061642206, 10693.518791654395, 10515.84226236607, 10596.523156457977, 10598.0, 10615.203859710553, 10661.893381066167, 42288.365674514906, 42624.78687606573, 42641.1471770566, 42676.57323426757, 42753.0, 3476.617572067071, 3557.62644922282, 3593.7125029997537, 3757.513786650642, 4057.1885622875543, 31749.523757143663, 31992.20019499508, 32274.6772532274, 32428.513572296433, 32601.0, 4184.769837658928, 4355.128974205171, 4364.432623758923, 4402.823887044515, 4504.864854054384, 339522.0, 339897.0, 340533.0, 342399.9999999975, 342846.0, 59117.40882591119, 59468.40531594715, 59881.40118598758, 59885.0, 59910.0, 61930.380696192464, 62619.0, 62635.6868215656, 62665.686671567266, 62687.37312626906, 15896.364145434285, 16064.036157830335, 16096.356145754273, 16096.597585060234, 16126.838731612768, 11404.429778511041, 11693.76612467763, 11695.298282103102, 11976.28142311464, 12980.35098245084, 15556.377744890304, 16973.236204370834, 17311.220995055253, 17458.30166793314, 18153.27386904509, 5880.764769409176, 5963.135345374896, 6027.517798576104, 6050.42520960508, 6067.271927368744, 895.1987970766128, 986.3835103060587, 989.8614194012844, 1053.4153544782566, 1076.39721755817, 30193.69806301909, 31065.0, 31245.53131703026, 31817.681823181472, 31865.522017169762, 1041.0526420957044, 1052.5368837711383, 1054.7204990677476, 1101.1521128730833, 1122.2761318949233, 15789.842101578837, 15931.920340398225, 16180.676386472205, 16563.310463378817, 16941.237644306035, 467356.0, 472038.0, 472200.0, 472558.0, 472873.0, 57097.429025710044, 57125.0, 57296.70813167427, 57495.71252143796, 57807.4219257802, 1182.9171957963033, 1241.1684171605045, 1297.7534268489146, 1304.1197191895433, 1343.1538130977422, 30543.694563054087, 31659.683403166597, 31994.840025800655, 32682.673173267962, 32807.83596082004, 49934.250986235966, 50583.241251381995, 50847.745761270955, 51167.744161278955, 51184.74407628087, 352737.0, 355161.0, 355670.0, 356215.0, 356621.0, 27259.72740272572, 28255.434891301647, 28268.293292667848, 29257.09828861117, 29619.0, 47035.764821175675, 47132.52867471419, 47182.76408617935, 48079.519204807504, 48757.0, 39620.603793961694, 39727.80136099301, 40150.79924600358, 40215.396769047904, 41701.0, 39627.2074558513, 39789.40315895206, 39891.202175956896, 41248.1750364985, 42167.789161053995, 39770.80114599408, 40301.0, 41695.0, 41761.0, 41774.58225417707, 31798.205044874063, 35151.0, 35307.82346088253, 36008.4598731014, 36198.45702314415, 27538.449231015667, 27581.0, 27981.0, 28009.57985630258, 28118.71881281161], "value": 19991.11806134215, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.06982682946307, 379.2225936829499, 395.81198930508015, 399.6722687396335, 417.9644730197933, 13610.903568271824, 15308.0, 16902.408415705453, 18297.725534116966, 18407.447776566725, 18051.458456246302, 18180.636387272247, 18280.634387312246, 18438.446846594623, 18772.71840922387, 5649.548036157097, 5892.558058145635, 5940.792072277472, 5955.106733989915, 5957.255343082114, 4814.446338671045, 5821.970890145543, 5852.824415267528, 5857.857717745042, 5886.587938844283, 5641.210230567724, 5774.047282198432, 5833.270841144857, 5838.124281357788, 5843.152742852284, 5751.827445176651, 5784.30588329399, 5835.212246346731, 5836.795712150097, 5840.06558950568, 4995.925061124086, 5062.2406639004075, 5232.162853943389, 5239.13554263546, 5239.685618862881, 22385.888070559544, 22510.099596016138, 22576.88711556448, 22670.773292267197, 22830.086796528118, 2358.8230882683865, 2437.7562243775483, 2476.3561474016706, 2801.817881837674, 3037.8329191894436, 13873.791893121612, 14039.578812635636, 14210.218437985906, 14691.118532887955, 14977.251137443085, 1847.5381154711322, 1925.8748181368305, 1927.6915693488975, 1947.8880558236126, 1957.4029920874127, 79047.0, 79915.60042199868, 80381.0, 81928.18071819325, 82237.0, 10311.381317120997, 10351.0, 10372.740681483023, 10631.255812093234, 10666.573337066431, 14917.0, 14935.925320373328, 14939.103653780809, 14947.177905215318, 14948.252587370587, 8121.390895682729, 8230.0, 8338.207870252316, 8398.454100483572, 8409.579521023925, 7671.769846904602, 7863.292303692695, 7928.365730741527, 8071.636776344961, 8140.511569305861, 6239.469645080128, 6465.127207826978, 6517.338078789879, 6536.379043990813, 6549.2468366137555, 1830.3410772122024, 1875.36237679187, 1935.9382257895297, 1948.4503683897974, 1966.3314473079222, 846.34408333542, 914.6844338703137, 919.8620206968874, 1034.8602938603342, 1036.5387402605918, 18808.529786755167, 19672.311469098513, 19848.40454786359, 19955.700664490043, 20032.399028029184, 987.8765154355706, 994.080475560104, 1008.5612758449951, 1012.1599072769584, 1023.22746326523, 10448.40270281074, 10891.782164356828, 11085.0, 11160.665180044612, 11271.211015228855, 209056.0, 411742.0, 420172.0, 424975.0, 426883.0, 77842.6107869457, 83543.0, 83596.0, 84101.15898841056, 84430.57784711037, 984.9704508864888, 1001.8797744270735, 1028.28534168752, 1031.5615863258085, 1031.7575369788094, 16299.699348090973, 16569.337226510805, 17100.65798684044, 17150.228239728993, 17301.134943252535, 38507.42238866475, 38912.2217555653, 38973.0, 39675.404868927566, 41206.793966029974, 50905.0, 51815.74092129666, 51917.740411299215, 52090.73954630203, 55019.0, 8236.958815205886, 8356.122607126408, 8406.537640429835, 8740.344474164336, 8827.911720882708, 12774.23354598727, 13184.670383240496, 13299.041150619103, 13460.798088028883, 13972.650683732989, 11659.358735269303, 11733.471993760131, 11856.881431185579, 11994.64016079519, 12418.130730848754, 11394.772104558027, 11496.54013839437, 11748.471318790504, 11788.882111179122, 11847.703807404883, 10694.304870183412, 11429.828552571884, 11482.253653512495, 11897.167198296036, 12042.976347010252, 7322.63386830667, 7661.88507172404, 8476.830463390821, 8573.957130214309, 8796.120387961153, 10617.628383006559, 11136.888631113909, 11332.093432525213, 11357.772844543228, 11673.649790506299], "value": 9169.066182775981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.9798403023951, 1481.851814818518, 1512.6445285357938, 1634.5341577650372, 1745.7643218165545, 7553.395728341733, 8800.647974081037, 9127.68053118141, 9134.314926380515, 9365.76585585359, 9184.908150918489, 9255.352125351228, 9266.0, 9266.860997085032, 9270.119338662833, 2291.46150654596, 2315.895784689693, 2318.1654604342425, 2322.326525307667, 2325.6860323856317, 2261.9321420357414, 2284.565932472825, 2323.8721870297127, 2324.8023917966993, 2328.69726935499, 2271.250487339174, 2276.169198242638, 2283.383486458655, 2298.092253559843, 2309.6535519672016, 2229.565234779219, 2238.40682219212, 2243.6410174372104, 2243.7083179186684, 2248.134468229727, 2221.577900198973, 2242.0, 2244.068711484732, 2244.2033078257145, 2249.0778780699834, 8974.0, 9083.09169083093, 9174.311926605464, 9184.632614695403, 9277.67528136512, 5032.924506132411, 5103.055934652101, 5149.0474262261605, 5151.5106064923775, 5153.510416510426, 3215.324781795826, 3309.933801323984, 3322.010190828274, 3399.4900764885465, 3401.9659803402146, 1128.5598616539555, 1133.3256712256195, 1139.3961200563715, 1145.604766355606, 1150.2925700694013, 12991.675208119872, 13041.86958130416, 13066.542671006564, 13085.0, 13086.47654093835, 4393.340998850198, 4520.59314661682, 4680.134175177603, 4693.06138772247, 4721.220998535272, 10128.493575321205, 10131.442770647685, 10134.0, 10136.138428233535, 10138.695839124837, 5371.892562148813, 5372.059889519319, 5372.301600791872, 5382.058139825514, 5388.703621300788, 4251.893702657459, 4319.913601728011, 4467.285234362552, 4855.635827312965, 5000.924986125211, 3735.402335626287, 3740.9064773380337, 3835.961640383616, 3845.057960799622, 3863.517060367454, 1131.8415421840948, 1142.2974870454611, 1146.879577644352, 1147.4262868565718, 1153.4716500636523, 851.535912927456, 882.6778225947482, 884.1423818895661, 890.5903284489078, 902.7652810269377, 5499.587530935116, 5514.420985796514, 5578.191162281447, 5672.773089076473, 5780.306363236438, 423.4706616729089, 424.72605169665394, 427.09882148666287, 428.6592159233417, 455.99088018240116, 3670.6512881276235, 3734.3091528067393, 3764.1906989997283, 3765.3975363942186, 3806.600306967784, 11274.830877536844, 11702.0, 11744.647660570197, 11762.117841161948, 11766.0, 11471.942640286912, 11664.88335116638, 11703.180777345504, 11717.76564468723, 11730.70673233159, 7482.3639990601405, 7712.884306735516, 8569.185927336894, 8595.742127736177, 8664.133586641285, 5489.0, 5525.640833345819, 5543.805966791143, 5627.296587926509, 5645.576581756302, 10047.849282260626, 10172.728408948882, 10238.747475555367, 10298.22763292771, 10432.947835261079, 10409.27135100566, 10656.307340022873, 10766.784664306513, 10777.51501182433, 10849.403282819521, 7521.135069466863, 7730.188330225245, 8533.829323413622, 8724.214820666171, 8733.912660873564, 8469.23776860061, 8777.824443511221, 8810.427322224035, 8951.239144672776, 8995.325350598601, 7380.655459669733, 7857.135715071452, 7970.242826931432, 7975.800604984922, 8089.0, 7560.395168386626, 7657.157712651715, 7771.766846994599, 7793.9220607793195, 7842.921570784448, 6997.790066298019, 7117.0, 7130.393916516947, 7143.428525718034, 7144.0, 4724.267738500401, 4873.634477414279, 4887.36464259644, 4943.77753001123, 4980.352554167935, 4826.975865120652, 4943.060818444484, 4996.150654388835, 5082.4917508250355, 5106.821261255838], "value": 4515.582501193827, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.5880443629936, 322.2346926050631, 328.64998776303236, 331.6467961620874, 341.660048251989, 5128.461511541291, 5280.1551751719635, 5340.890348312414, 5353.14349704047, 5365.195220716894, 5095.210242412432, 5245.68525888446, 5272.9981303552395, 5293.808892999074, 5311.681299122046, 4178.832846686144, 4192.7484350938885, 4219.2405367033925, 4227.154569086188, 4252.8511502097435, 3933.7443066200603, 4097.508299004109, 4211.642010429117, 4225.218334608107, 4249.107687385653, 4080.73497215864, 4099.24164029654, 4103.5280942691525, 4112.588741125878, 4122.567130451304, 4098.897527561805, 4104.42538044674, 4106.32245679461, 4122.072533679928, 4126.360414135814, 3634.818259087035, 3647.9817600912093, 3674.926501469956, 3677.5586929568353, 3678.0, 4995.8509542804995, 5070.416902056274, 5072.289879416885, 5098.4136824265315, 5108.693478391308, 4641.350210970467, 4663.766811659403, 4664.836730714409, 4678.461976872669, 4689.484156742756, 5441.29263195782, 5450.618456708032, 5452.4274951129955, 5457.345118585756, 5458.918116228259, 3813.9427908581392, 3917.3536366499493, 3927.764334139932, 3961.485006949107, 3973.0861901762755, 5599.692016939067, 5639.915401268984, 5655.5475561954945, 5673.886522269531, 5689.345725241609, 4022.7127319258143, 4058.127502586958, 4063.923060388999, 4075.2664520386024, 4081.4082507821972, 4017.8593749218635, 4123.835046598102, 4127.484064491939, 4135.813888375031, 4147.440095587057, 4659.184642687516, 4675.649326300473, 4679.485256621769, 4682.578567928903, 4693.530646935279, 5526.198701188386, 5528.894221155785, 5553.111502159716, 5571.359293681197, 5586.24585680936, 1315.4343632238038, 1334.1128149780482, 1388.3201326394974, 1402.2708191740237, 1410.5768269519103, 877.9429337093069, 891.6032365597376, 906.7667971558675, 917.060013486182, 929.1730359979623, 4674.883127921829, 4689.835855745101, 4698.788554514987, 4713.34013238147, 4718.504557021532, 4951.752412379366, 4958.6776859504735, 4961.404631444249, 4962.702237865739, 4963.255511673206, 4479.484859241164, 4729.858104256878, 4735.479097299294, 4744.620430365562, 4834.292796034232, 2596.6234895939983, 2605.5310044191847, 2609.739026097413, 2619.986900065526, 2622.7377262274003, 4974.950250497521, 4987.850364488999, 5029.0, 5033.295338652591, 5118.974405128025, 4852.975735121302, 5024.899502009939, 5031.622628302892, 5036.532099271404, 5052.520010598987, 4997.67654509747, 5047.747612619354, 5056.393232812086, 5056.951934466547, 5061.4179369373, 4954.925676114789, 4978.2781496682055, 4979.427365852974, 4983.4518202996915, 4996.825111121093, 4608.170529304757, 4873.562299121839, 4886.975565122151, 4896.531040687851, 4898.0, 5044.8234311798915, 5049.798008079636, 5050.595952323879, 5073.670211436245, 5084.847454576368, 3684.465752465931, 3695.3902606070774, 3701.759385639925, 3706.83319250629, 3722.9627703722617, 4793.664443488922, 4928.088303663833, 5001.64988450805, 5001.949980500149, 5055.924161137512, 4791.6406269529225, 4819.7349145795915, 4826.975865120652, 4864.889168652483, 4872.269159626085, 4824.34871292371, 4825.0, 4841.806327746851, 4859.538343857328, 4868.537488938545, 4778.276382461963, 4808.110499557593, 4814.772233080682, 4825.5174482551465, 5088.7786931137, 5572.247746554163, 5595.972020140036, 5602.13166959122, 5616.634918730269, 5619.409961953936, 5445.455454454523, 5463.972680136574, 5493.862653433696, 5504.394516603091, 5505.339359276913], "value": 3989.727311047725, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.7162099349765, 716.9318914703102, 727.076612701869, 775.7944144801628, 812.3825892321834, 14831.332590033448, 16298.918505407471, 16472.917635411806, 16503.33986640536, 16514.50456486306, 15023.549293521184, 15152.84847151528, 15187.0, 15217.239138043109, 15227.0, 784.9018872640905, 805.1586092533288, 806.8466991271669, 806.8951036365264, 807.1807115777491, 788.5938741548078, 790.3203245209115, 790.3282210121408, 790.5809920741988, 790.7113903425237, 823.2138307915956, 824.171707434028, 824.2540500846713, 824.6289169873581, 826.0211649195722, 817.6851912013917, 817.8527864984301, 818.2104269380018, 818.5825229133155, 819.1030821250721, 605.4550904186224, 605.8727667189895, 606.0122001138117, 606.2452246952528, 606.6390497653889, 12810.551630692975, 12826.102172847905, 12850.35748212595, 12865.0, 12870.871291287061, 12420.0, 12853.67865803353, 12890.677733056655, 13208.273544955024, 13613.183209007491, 11102.944485277603, 11119.666410007712, 11137.554497820078, 11479.196456248066, 11790.0, 3729.3287208302495, 3739.0, 3762.8871133865755, 3826.885193444173, 3936.094698219397, 75228.62385688037, 76042.0, 76273.0, 76928.23071769267, 78150.44968929178, 9940.502974851228, 9945.502724863727, 9987.700368988943, 10012.749681257881, 10380.948095259475, 20169.49576260576, 20200.29298974529, 20282.290119845733, 20301.898490507454, 20307.086181121886, 7951.721689740947, 8118.0, 8548.74353769388, 8733.170348816851, 8776.385653004356, 4588.26587745959, 4691.179043667345, 4829.44461386945, 5055.418626857959, 5099.770510327045, 3433.8496603637564, 3579.2125732338845, 3581.9641803582153, 3600.1179710970455, 3604.675579197832, 553.2752094755882, 560.9971950140223, 567.8523583868141, 568.8293511946399, 637.0698779781478, 559.6166625861298, 579.3453397660617, 627.1564745417406, 701.7894631610599, 722.3823630795594, 9055.864162037575, 9113.407628504126, 9135.177833994972, 9524.428534287965, 9785.706428807147, 565.4599857136425, 690.1752405874931, 691.9584824910521, 714.4820005496077, 732.6593134192626, 4491.73049617024, 4700.200965835747, 4770.784538958445, 4781.952180478151, 4845.152098382769, 118658.0, 119036.40481797709, 122422.38788806, 122427.0, 125233.0, 56888.0, 63829.00331597277, 64166.358336416975, 66044.0, 110957.0, 57989.710051449474, 60554.0, 69081.30918690849, 77601.83597246045, 84466.5776671125, 9280.164785169367, 9854.310198286052, 10016.749581260527, 10125.949370253102, 10148.847767283349, 43083.0, 44628.55371446244, 50509.49490505048, 53349.73325133349, 54490.72754636202, 46020.30969535393, 62788.68605656942, 63224.0, 64278.357216427234, 75101.62449187718, 13493.906553928193, 13576.932115339756, 13615.455381784617, 13734.107283026571, 13929.164250145024, 14734.557963260691, 14893.255337233311, 15278.77081843751, 15366.846331536988, 15657.0, 12632.431540580512, 13194.736105278032, 13452.865471345161, 13600.591982240549, 13885.375158117708, 12348.753024939631, 12584.685382865502, 12811.487540498276, 13223.206607603575, 13356.0, 12991.480340786262, 13208.537701180412, 13424.328783560588, 13655.112417692819, 13920.443182272596, 11414.771704565695, 11675.182737208313, 11741.823872642088, 11742.530298787953, 11973.700657483632, 10362.481875906326, 11250.649922009412, 11282.605108821152, 11830.822537662114, 12007.159498834997], "value": 7266.331911942778, "unit": "score"}], "name": "dromaeo_css", "value": 7063.541525713147, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 112.0, 112.0, 111.0, 112.0, 111.0, 110.0, 113.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 121.0, 169.0, 122.0, 124.0, 129.0, 126.0, 167.0, 129.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 111.0, 84.0, 77.0, 76.0, 75.0, 101.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 108.0, 82.0, 82.0, 84.0, 102.0, 83.0, 82.0, 114.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 52.0, 52.0, 53.0, 54.0, 55.0, 54.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 78.0, 79.0, 80.0, 82.0, 79.0, 84.0, 81.0, 78.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 133.0, 132.0, 139.0, 130.0, 138.0, 135.0, 181.0, 137.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 170.0, 166.0, 166.0, 168.0, 169.0, 163.0, 166.0, 170.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 64.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1504.7, "alertThreshold": 2.0}]} 18:56:41 INFO - Return code: 0 18:56:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:56:41 INFO - # TBPL SUCCESS # 18:56:41 INFO - Running post-action listener: _resource_record_post_action 18:56:41 INFO - [mozharness: 2016-10-13 01:56:41.985241Z] Finished run-tests step (success) 18:56:41 INFO - Running post-run listener: _resource_record_post_run 18:56:42 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 8192; Write bytes: 400281600; Read time: 156; Write time: 323668 18:56:42 INFO - TinderboxPrint: CPU usage
13.1% 18:56:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 156 18:56:42 INFO - TinderboxPrint: I/O write bytes / time
400,281,600 / 323,668 18:56:42 INFO - TinderboxPrint: CPU idle
8,296.7 (86.9%) 18:56:42 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 18:56:42 INFO - TinderboxPrint: Swap in / out
0 / 0 18:56:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110587904; Read time: 0; Write time: 113836 18:56:42 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289693696; Read time: 148; Write time: 209832 18:56:42 INFO - Running post-run listener: _upload_blobber_files 18:56:42 INFO - Blob upload gear active. 18:56:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:56:42 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 18:56:42 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:56:42 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:56:43 INFO - (blobuploader) - INFO - Open directory for files ... 18:56:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:56:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:56:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:44 INFO - (blobuploader) - INFO - Done attempting. 18:56:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:56:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:56:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:47 INFO - (blobuploader) - INFO - Done attempting. 18:56:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:56:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:56:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:47 INFO - (blobuploader) - INFO - Done attempting. 18:56:47 INFO - (blobuploader) - INFO - Iteration through files over. 18:56:47 INFO - Return code: 0 18:56:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:56:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:56:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be14c77190dec0c06dca446a09fcbd10f3d1b12a0e60e44f580b1ec27f893f1043d5c655aaafaa369f3805fd21bbf3f72da45be0d94adf2f1cfc5db7a1374e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd136116f930cbd6e45f1faccfc493c8e119c1cada39dc877e69bab927653084a963ae2d9d42b553210f421ec4c6d3b95cef958d290dd1bf0985479cfaabd6e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db8daa72101b468c8807889ff128abb759999fae75910b6f86c529c12837913378fde82d083f04cdf03cf11508e9817f3231afe25a3c16e56f4880b5bb5bd55"} 18:56:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:56:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:56:47 INFO - Contents: 18:56:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be14c77190dec0c06dca446a09fcbd10f3d1b12a0e60e44f580b1ec27f893f1043d5c655aaafaa369f3805fd21bbf3f72da45be0d94adf2f1cfc5db7a1374e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd136116f930cbd6e45f1faccfc493c8e119c1cada39dc877e69bab927653084a963ae2d9d42b553210f421ec4c6d3b95cef958d290dd1bf0985479cfaabd6e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db8daa72101b468c8807889ff128abb759999fae75910b6f86c529c12837913378fde82d083f04cdf03cf11508e9817f3231afe25a3c16e56f4880b5bb5bd55"} 18:56:47 INFO - Running post-run listener: copy_logs_to_upload_dir 18:56:47 INFO - Copying logs to upload dir... 18:56:47 INFO - mkdir: /builds/slave/test/build/upload/logs 18:56:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.947716 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-10-12 18:56:47.327330) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 18:56:47.331133) ========= 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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be14c77190dec0c06dca446a09fcbd10f3d1b12a0e60e44f580b1ec27f893f1043d5c655aaafaa369f3805fd21bbf3f72da45be0d94adf2f1cfc5db7a1374e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd136116f930cbd6e45f1faccfc493c8e119c1cada39dc877e69bab927653084a963ae2d9d42b553210f421ec4c6d3b95cef958d290dd1bf0985479cfaabd6e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db8daa72101b468c8807889ff128abb759999fae75910b6f86c529c12837913378fde82d083f04cdf03cf11508e9817f3231afe25a3c16e56f4880b5bb5bd55"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011407 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476311401/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be14c77190dec0c06dca446a09fcbd10f3d1b12a0e60e44f580b1ec27f893f1043d5c655aaafaa369f3805fd21bbf3f72da45be0d94adf2f1cfc5db7a1374e94", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd136116f930cbd6e45f1faccfc493c8e119c1cada39dc877e69bab927653084a963ae2d9d42b553210f421ec4c6d3b95cef958d290dd1bf0985479cfaabd6e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db8daa72101b468c8807889ff128abb759999fae75910b6f86c529c12837913378fde82d083f04cdf03cf11508e9817f3231afe25a3c16e56f4880b5bb5bd55"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 18:56:47.356625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:56:47.356963) ========= 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=836cfdc75598d54cb747368e000001a9-1476322568.176162-196557214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:56:47.443525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 18:56:47.443902) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 18:56:47.444260) ========= ========= Total master_lag: 0.17 =========