builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1474908399.78 results: success (0) buildid: 20160926093504 builduid: 7dbc3d2a20e544fbaf8003975d0487c0 revision: c55bcb7c777ea09431b4d16903ed079ae5632648 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:39.778277) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:39.779428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:39.780092) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:39.796250) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:39.796545) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-26 09:46:39-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 214M=0s 2016-09-26 09:46:40 (214 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.361807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.175675) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.176388) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019582 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.229540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.230357) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c55bcb7c777ea09431b4d16903ed079ae5632648 --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 c55bcb7c777ea09431b4d16903ed079ae5632648 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-26 09:46:40,418 truncating revision to first 12 chars 2016-09-26 09:46:40,418 Setting DEBUG logging. 2016-09-26 09:46:40,418 attempt 1/10 2016-09-26 09:46:40,418 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c55bcb7c777e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 09:46:40,726 unpacking tar archive at: fx-team-c55bcb7c777e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.899247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.899881) ========= script_repo_revision: c55bcb7c777ea09431b4d16903ed079ae5632648 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.900714) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.901472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 09:46:40.913471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-26 09:46:40.913786) ========= /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=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:46:41 INFO - MultiFileLogger online at 20160926 09:46:41 in /builds/slave/test 09:46:41 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 09:46:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:41 INFO - {'append_to_log': False, 09:46:41 INFO - 'base_work_dir': '/builds/slave/test', 09:46:41 INFO - 'blob_upload_branch': 'Fx-Team', 09:46:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:41 INFO - 'branch': 'Fx-Team', 09:46:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:41 INFO - 'config_files': ('talos/linux_config.py',), 09:46:41 INFO - 'default_actions': ('clobber', 09:46:41 INFO - 'read-buildbot-config', 09:46:41 INFO - 'download-and-extract', 09:46:41 INFO - 'populate-webroot', 09:46:41 INFO - 'create-virtualenv', 09:46:41 INFO - 'install', 09:46:41 INFO - 'run-tests'), 09:46:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:41 INFO - 'download_minidump_stackwalk': True, 09:46:41 INFO - 'download_symbols': 'ondemand', 09:46:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:41 INFO - 'installer_path': 'installer.exe', 09:46:41 INFO - 'log_level': 'info', 09:46:41 INFO - 'log_name': 'talos', 09:46:41 INFO - 'log_to_console': True, 09:46:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:46:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:46:41 INFO - 'opt_config_files': (), 09:46:41 INFO - 'pip_index': False, 09:46:41 INFO - 'sps_profile': False, 09:46:41 INFO - 'sps_profile_interval': 0, 09:46:41 INFO - 'suite': 'dromaeojs', 09:46:41 INFO - 'system_bits': '32', 09:46:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:41 INFO - 'title': 'talos-linux64-ix-004', 09:46:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:41 INFO - 'use_talos_json': True, 09:46:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:41 INFO - 'work_dir': 'build'} 09:46:41 INFO - [mozharness: 2016-09-26 16:46:41.203225Z] Running clobber step. 09:46:41 INFO - Running pre-action listener: _resource_record_pre_action 09:46:41 INFO - Running main action method: clobber 09:46:41 INFO - rmtree: /builds/slave/test/build 09:46:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:41 INFO - Running post-action listener: _resource_record_post_action 09:46:41 INFO - [mozharness: 2016-09-26 16:46:41.663571Z] Finished clobber step (success) 09:46:41 INFO - [mozharness: 2016-09-26 16:46:41.663670Z] Running read-buildbot-config step. 09:46:41 INFO - Running pre-action listener: _resource_record_pre_action 09:46:41 INFO - Running main action method: read_buildbot_config 09:46:41 INFO - Using buildbot properties: 09:46:41 INFO - { 09:46:41 INFO - "project": "", 09:46:41 INFO - "product": "firefox", 09:46:41 INFO - "script_repo_revision": "production", 09:46:41 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:46:41 INFO - "repository": "", 09:46:41 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 09:46:41 INFO - "buildid": "20160926093504", 09:46:41 INFO - "stage_platform": "linux64-pgo", 09:46:41 INFO - "basedir": "/builds/slave/test", 09:46:41 INFO - "buildnumber": 477, 09:46:41 INFO - "platform": "ubuntu64_hw", 09:46:41 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:46:41 INFO - "slavebuilddir": "test", 09:46:41 INFO - "branch": "fx-team", 09:46:41 INFO - "revision": "c55bcb7c777ea09431b4d16903ed079ae5632648", 09:46:41 INFO - "slavename": "talos-linux64-ix-004", 09:46:41 INFO - "builduid": "7dbc3d2a20e544fbaf8003975d0487c0", 09:46:41 INFO - "repo_path": "integration/fx-team" 09:46:41 INFO - } 09:46:41 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:46:41 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 09:46:41 INFO - Running post-action listener: _resource_record_post_action 09:46:41 INFO - [mozharness: 2016-09-26 16:46:41.666046Z] Finished read-buildbot-config step (success) 09:46:41 INFO - [mozharness: 2016-09-26 16:46:41.666133Z] Running download-and-extract step. 09:46:41 INFO - Running pre-action listener: _resource_record_pre_action 09:46:41 INFO - Running main action method: download_and_extract 09:46:41 INFO - mkdir: /builds/slave/test/build/tests 09:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:41 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:46:41 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/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 09:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/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 09:46:41 INFO - Downloaded 1361 bytes. 09:46:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:46:41 INFO - Using the following test package requirements: 09:46:41 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:46:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:46:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:46:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'jsshell-linux-x86_64.zip'], 09:46:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:46:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:46:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:46:41 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:46:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:46:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:46:41 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:46:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:46:41 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:46:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:46:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:46:41 INFO - Expected file size: 21981419 09:46:46 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 09:46:46 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:46:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:46:46 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:46:46 INFO - Expected file size: 21981419 09:46:50 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:46:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:46:50 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:46:50 INFO - Expected file size: 14414057 09:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:51 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:46:51 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/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 09:46:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/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 09:46:51 INFO - Downloaded 59695080 bytes. 09:46:51 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:46:51 INFO - mkdir: /builds/slave/test/properties 09:46:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:46:51 INFO - Writing to file /builds/slave/test/properties/build_url 09:46:51 INFO - Contents: 09:46:51 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:46:52 INFO - Running post-action listener: _resource_record_post_action 09:46:52 INFO - Running post-action listener: set_extra_try_arguments 09:46:52 INFO - [mozharness: 2016-09-26 16:46:52.316835Z] Finished download-and-extract step (success) 09:46:52 INFO - [mozharness: 2016-09-26 16:46:52.317070Z] Running populate-webroot step. 09:46:52 INFO - Running pre-action listener: _resource_record_pre_action 09:46:52 INFO - Running main action method: populate_webroot 09:46:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:46:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:46:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:46:52 INFO - '--tppagecycles', 09:46:52 INFO - '7'], 09:46:52 INFO - 'tests': ['tsvgm']}}, 09:46:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:46:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:46:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:46:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:46:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:46:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['damp', 'tps']}, 09:46:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'tests': ['damp', 'tps']}, 09:46:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['dromaeo_dom']}, 09:46:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:46:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['basic_compositor_video']}, 09:46:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:46:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'other-e10s': {'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['a11yr', 09:46:52 INFO - 'ts_paint', 09:46:52 INFO - 'tpaint', 09:46:52 INFO - 'sessionrestore', 09:46:52 INFO - 'sessionrestore_no_auto_restore', 09:46:52 INFO - 'tabpaint']}, 09:46:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['tsvgx', 09:46:52 INFO - 'tsvgr_opacity', 09:46:52 INFO - 'tart', 09:46:52 INFO - 'tscrollx', 09:46:52 INFO - 'cart']}, 09:46:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:46:52 INFO - 'tsvgr_opacity', 09:46:52 INFO - 'tart', 09:46:52 INFO - 'tscrollx', 09:46:52 INFO - 'cart']}, 09:46:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'talos_options': ['--disable-e10s'], 09:46:52 INFO - 'tests': ['tp5o']}, 09:46:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'tests': ['tp5o']}, 09:46:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'talos_options': ['--disable-e10s', 09:46:52 INFO - '--xperf_path', 09:46:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:52 INFO - 'tests': ['tp5n']}, 09:46:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:46:52 INFO - 'talos_options': ['--xperf_path', 09:46:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:46:52 INFO - 'tests': ['tp5n']}}, 09:46:52 INFO - 'talos.zip': {'path': '', 09:46:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:46:52 INFO - Running post-action listener: _resource_record_post_action 09:46:52 INFO - [mozharness: 2016-09-26 16:46:52.332795Z] Finished populate-webroot step (success) 09:46:52 INFO - [mozharness: 2016-09-26 16:46:52.332900Z] Running create-virtualenv step. 09:46:52 INFO - Running pre-action listener: _resource_record_pre_action 09:46:52 INFO - Running main action method: create_virtualenv 09:46:52 INFO - Creating virtualenv /builds/slave/test/build/venv 09:46:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:46:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:46:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:46:52 INFO - Using real prefix '/usr' 09:46:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:46:52 INFO - Installing distribute.............................................................................................................................................................................................done. 09:46:54 INFO - Installing pip.................done. 09:46:54 INFO - Return code: 0 09:46:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:46:55 INFO - Reading from file tmpfile_stdout 09:46:55 INFO - Output received: 09:46:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:46:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:46:55 INFO - Using env: {'DISPLAY': ':0', 09:46:55 INFO - 'HOME': '/home/cltbld', 09:46:55 INFO - 'LANG': 'en_US.UTF-8', 09:46:55 INFO - 'LANGUAGE': 'en_US:en', 09:46:55 INFO - 'LOGNAME': 'cltbld', 09:46:55 INFO - 'MAIL': '/var/mail/cltbld', 09:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:55 INFO - 'MOZ_NO_REMOTE': '1', 09:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:46:55 INFO - 'NO_EM_RESTART': '1', 09:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:55 INFO - 'PWD': '/builds/slave/test', 09:46:55 INFO - 'SHELL': '/bin/bash', 09:46:55 INFO - 'SHLVL': '1', 09:46:55 INFO - 'TERM': 'linux', 09:46:55 INFO - 'TMOUT': '86400', 09:46:55 INFO - 'USER': 'cltbld', 09:46:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:55 INFO - '_': '/tools/buildbot/bin/python'} 09:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:46:55 INFO - Cleaning up... 09:46:55 INFO - Return code: 0 09:46:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:46:55 INFO - Using env: {'DISPLAY': ':0', 09:46:55 INFO - 'HOME': '/home/cltbld', 09:46:55 INFO - 'LANG': 'en_US.UTF-8', 09:46:55 INFO - 'LANGUAGE': 'en_US:en', 09:46:55 INFO - 'LOGNAME': 'cltbld', 09:46:55 INFO - 'MAIL': '/var/mail/cltbld', 09:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:55 INFO - 'MOZ_NO_REMOTE': '1', 09:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:46:55 INFO - 'NO_EM_RESTART': '1', 09:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:55 INFO - 'PWD': '/builds/slave/test', 09:46:55 INFO - 'SHELL': '/bin/bash', 09:46:55 INFO - 'SHLVL': '1', 09:46:55 INFO - 'TERM': 'linux', 09:46:55 INFO - 'TMOUT': '86400', 09:46:55 INFO - 'USER': 'cltbld', 09:46:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:55 INFO - '_': '/tools/buildbot/bin/python'} 09:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:55 INFO - Downloading/unpacking psutil>=3.1.1 09:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:46:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:57 INFO - Installing collected packages: psutil 09:46:57 INFO - Running setup.py install for psutil 09:46:57 INFO - building 'psutil._psutil_linux' extension 09:46:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:46:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:46:57 INFO - building 'psutil._psutil_posix' extension 09:46:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:46:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:46:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:46:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:46:58 INFO - Successfully installed psutil 09:46:58 INFO - Cleaning up... 09:46:58 INFO - Return code: 0 09:46:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:46:58 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 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:46:58 INFO - Using env: {'DISPLAY': ':0', 09:46:58 INFO - 'HOME': '/home/cltbld', 09:46:58 INFO - 'LANG': 'en_US.UTF-8', 09:46:58 INFO - 'LANGUAGE': 'en_US:en', 09:46:58 INFO - 'LOGNAME': 'cltbld', 09:46:58 INFO - 'MAIL': '/var/mail/cltbld', 09:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:58 INFO - 'MOZ_NO_REMOTE': '1', 09:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:46:58 INFO - 'NO_EM_RESTART': '1', 09:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:58 INFO - 'PWD': '/builds/slave/test', 09:46:58 INFO - 'SHELL': '/bin/bash', 09:46:58 INFO - 'SHLVL': '1', 09:46:58 INFO - 'TERM': 'linux', 09:46:58 INFO - 'TMOUT': '86400', 09:46:58 INFO - 'USER': 'cltbld', 09:46:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:58 INFO - '_': '/tools/buildbot/bin/python'} 09:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:00 INFO - Installing collected packages: mozsystemmonitor 09:47:00 INFO - Running setup.py install for mozsystemmonitor 09:47:00 INFO - Successfully installed mozsystemmonitor 09:47:00 INFO - Cleaning up... 09:47:00 INFO - Return code: 0 09:47:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:47:00 INFO - Using env: {'DISPLAY': ':0', 09:47:00 INFO - 'HOME': '/home/cltbld', 09:47:00 INFO - 'LANG': 'en_US.UTF-8', 09:47:00 INFO - 'LANGUAGE': 'en_US:en', 09:47:00 INFO - 'LOGNAME': 'cltbld', 09:47:00 INFO - 'MAIL': '/var/mail/cltbld', 09:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:00 INFO - 'MOZ_NO_REMOTE': '1', 09:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:00 INFO - 'NO_EM_RESTART': '1', 09:47:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:00 INFO - 'PWD': '/builds/slave/test', 09:47:00 INFO - 'SHELL': '/bin/bash', 09:47:00 INFO - 'SHLVL': '1', 09:47:00 INFO - 'TERM': 'linux', 09:47:00 INFO - 'TMOUT': '86400', 09:47:00 INFO - 'USER': 'cltbld', 09:47:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:00 INFO - '_': '/tools/buildbot/bin/python'} 09:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:00 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:02 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:02 INFO - warning: no files found matching '*.txt' 09:47:02 INFO - no previously-included directories found matching 'build' 09:47:02 INFO - no previously-included directories found matching 'dist' 09:47:02 INFO - no previously-included directories found matching '.git*' 09:47:02 INFO - Installing collected packages: jsonschema, functools32 09:47:02 INFO - Running setup.py install for functools32 09:47:02 INFO - warning: no files found matching '*.txt' 09:47:02 INFO - no previously-included directories found matching 'build' 09:47:02 INFO - no previously-included directories found matching 'dist' 09:47:02 INFO - no previously-included directories found matching '.git*' 09:47:02 INFO - Successfully installed jsonschema functools32 09:47:02 INFO - Cleaning up... 09:47:02 INFO - Return code: 0 09:47:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:47:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:47:02 INFO - Using env: {'DISPLAY': ':0', 09:47:02 INFO - 'HOME': '/home/cltbld', 09:47:02 INFO - 'LANG': 'en_US.UTF-8', 09:47:02 INFO - 'LANGUAGE': 'en_US:en', 09:47:02 INFO - 'LOGNAME': 'cltbld', 09:47:02 INFO - 'MAIL': '/var/mail/cltbld', 09:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:02 INFO - 'MOZ_NO_REMOTE': '1', 09:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:02 INFO - 'NO_EM_RESTART': '1', 09:47:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:02 INFO - 'PWD': '/builds/slave/test', 09:47:02 INFO - 'SHELL': '/bin/bash', 09:47:02 INFO - 'SHLVL': '1', 09:47:02 INFO - 'TERM': 'linux', 09:47:02 INFO - 'TMOUT': '86400', 09:47:02 INFO - 'USER': 'cltbld', 09:47:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:02 INFO - '_': '/tools/buildbot/bin/python'} 09:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:47:03 INFO - Cleaning up... 09:47:03 INFO - Return code: 0 09:47:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:03 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 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:47:03 INFO - Using env: {'DISPLAY': ':0', 09:47:03 INFO - 'HOME': '/home/cltbld', 09:47:03 INFO - 'LANG': 'en_US.UTF-8', 09:47:03 INFO - 'LANGUAGE': 'en_US:en', 09:47:03 INFO - 'LOGNAME': 'cltbld', 09:47:03 INFO - 'MAIL': '/var/mail/cltbld', 09:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:03 INFO - 'MOZ_NO_REMOTE': '1', 09:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:03 INFO - 'NO_EM_RESTART': '1', 09:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:03 INFO - 'PWD': '/builds/slave/test', 09:47:03 INFO - 'SHELL': '/bin/bash', 09:47:03 INFO - 'SHLVL': '1', 09:47:03 INFO - 'TERM': 'linux', 09:47:03 INFO - 'TMOUT': '86400', 09:47:03 INFO - 'USER': 'cltbld', 09:47:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:03 INFO - '_': '/tools/buildbot/bin/python'} 09:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:03 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:05 INFO - Downloading docopt-0.6.1.tar.gz 09:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:06 INFO - Running setup.py install for blobuploader 09:47:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:06 INFO - Running setup.py install for requests 09:47:06 INFO - Running setup.py install for docopt 09:47:06 INFO - Successfully installed blobuploader requests docopt 09:47:06 INFO - Cleaning up... 09:47:06 INFO - Return code: 0 09:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:06 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 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:06 INFO - Using env: {'DISPLAY': ':0', 09:47:06 INFO - 'HOME': '/home/cltbld', 09:47:06 INFO - 'LANG': 'en_US.UTF-8', 09:47:06 INFO - 'LANGUAGE': 'en_US:en', 09:47:06 INFO - 'LOGNAME': 'cltbld', 09:47:06 INFO - 'MAIL': '/var/mail/cltbld', 09:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:06 INFO - 'MOZ_NO_REMOTE': '1', 09:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:06 INFO - 'NO_EM_RESTART': '1', 09:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:06 INFO - 'PWD': '/builds/slave/test', 09:47:06 INFO - 'SHELL': '/bin/bash', 09:47:06 INFO - 'SHLVL': '1', 09:47:06 INFO - 'TERM': 'linux', 09:47:06 INFO - 'TMOUT': '86400', 09:47:06 INFO - 'USER': 'cltbld', 09:47:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:06 INFO - '_': '/tools/buildbot/bin/python'} 09:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:06 INFO - Running setup.py (path:/tmp/pip-q9xO3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:06 INFO - Running setup.py (path:/tmp/pip-C7SO5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:07 INFO - Running setup.py (path:/tmp/pip-bSF62h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:07 INFO - Running setup.py (path:/tmp/pip-pf3Ae3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:07 INFO - Running setup.py (path:/tmp/pip-0V1N3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:07 INFO - Running setup.py (path:/tmp/pip-56Hdzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:07 INFO - Running setup.py (path:/tmp/pip-Oecvgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:07 INFO - Running setup.py (path:/tmp/pip-b16lOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:07 INFO - Running setup.py (path:/tmp/pip-QiSXBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:07 INFO - Running setup.py (path:/tmp/pip-ChULHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:07 INFO - Running setup.py (path:/tmp/pip-iMUCo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:07 INFO - Running setup.py (path:/tmp/pip-SWezd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:07 INFO - Running setup.py (path:/tmp/pip-bsvCao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:07 INFO - Running setup.py (path:/tmp/pip-TuVzx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:07 INFO - Running setup.py (path:/tmp/pip-eEMaAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:07 INFO - Running setup.py (path:/tmp/pip-qQnGg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:08 INFO - Running setup.py (path:/tmp/pip-WyAEAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:08 INFO - Running setup.py install for manifestparser 09:47:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Running setup.py install for mozcrash 09:47:08 INFO - Running setup.py install for mozdebug 09:47:08 INFO - Running setup.py install for mozdevice 09:47:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Running setup.py install for mozfile 09:47:08 INFO - Running setup.py install for mozhttpd 09:47:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Running setup.py install for mozinfo 09:47:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Running setup.py install for mozInstall 09:47:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:08 INFO - Running setup.py install for mozleak 09:47:08 INFO - Running setup.py install for mozlog 09:47:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Running setup.py install for moznetwork 09:47:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Running setup.py install for mozprocess 09:47:09 INFO - Running setup.py install for mozprofile 09:47:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Running setup.py install for mozrunner 09:47:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Running setup.py install for mozscreenshot 09:47:09 INFO - Running setup.py install for moztest 09:47:09 INFO - Running setup.py install for mozversion 09:47:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:09 INFO - Cleaning up... 09:47:09 INFO - Return code: 0 09:47:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:09 INFO - Using env: {'DISPLAY': ':0', 09:47:09 INFO - 'HOME': '/home/cltbld', 09:47:09 INFO - 'LANG': 'en_US.UTF-8', 09:47:09 INFO - 'LANGUAGE': 'en_US:en', 09:47:09 INFO - 'LOGNAME': 'cltbld', 09:47:09 INFO - 'MAIL': '/var/mail/cltbld', 09:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:09 INFO - 'MOZ_NO_REMOTE': '1', 09:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:09 INFO - 'NO_EM_RESTART': '1', 09:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:09 INFO - 'PWD': '/builds/slave/test', 09:47:09 INFO - 'SHELL': '/bin/bash', 09:47:09 INFO - 'SHLVL': '1', 09:47:09 INFO - 'TERM': 'linux', 09:47:09 INFO - 'TMOUT': '86400', 09:47:09 INFO - 'USER': 'cltbld', 09:47:09 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:09 INFO - '_': '/tools/buildbot/bin/python'} 09:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:10 INFO - Running setup.py (path:/tmp/pip-Cb1hAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:10 INFO - Running setup.py (path:/tmp/pip-CgeFd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:10 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)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:10 INFO - Running setup.py (path:/tmp/pip-lzmH71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:10 INFO - Running setup.py (path:/tmp/pip-xDBnF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:10 INFO - Running setup.py (path:/tmp/pip-qjZ7pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:10 INFO - Running setup.py (path:/tmp/pip-hJK8ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:10 INFO - Running setup.py (path:/tmp/pip-HS5OBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:10 INFO - Running setup.py (path:/tmp/pip-KIh9vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:10 INFO - Running setup.py (path:/tmp/pip-TIKGj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:10 INFO - Running setup.py (path:/tmp/pip-SfdR3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:10 INFO - Running setup.py (path:/tmp/pip-kL1Nyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:10 INFO - Running setup.py (path:/tmp/pip-vqK_bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:10 INFO - Running setup.py (path:/tmp/pip-S2O52V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:11 INFO - Running setup.py (path:/tmp/pip-9_spFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:11 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)) 09:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:11 INFO - Running setup.py (path:/tmp/pip-X8Jieb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:11 INFO - Running setup.py (path:/tmp/pip-cPVmhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:11 INFO - Running setup.py (path:/tmp/pip-8BLVuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:47:11 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)) 09:47:11 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)) 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:13 INFO - Downloading blessings-1.6.tar.gz 09:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:13 INFO - Installing collected packages: blessings 09:47:13 INFO - Running setup.py install for blessings 09:47:13 INFO - Successfully installed blessings 09:47:13 INFO - Cleaning up... 09:47:13 INFO - Return code: 0 09:47:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:13 INFO - Reading from file tmpfile_stdout 09:47:13 INFO - Current package versions: 09:47:13 INFO - argparse == 1.2.1 09:47:13 INFO - blessings == 1.6 09:47:13 INFO - blobuploader == 1.2.4 09:47:13 INFO - docopt == 0.6.1 09:47:13 INFO - functools32 == 3.2.3-2 09:47:13 INFO - jsonschema == 2.5.1 09:47:13 INFO - manifestparser == 1.1 09:47:13 INFO - mozInstall == 1.12 09:47:13 INFO - mozcrash == 0.17 09:47:13 INFO - mozdebug == 0.1 09:47:13 INFO - mozdevice == 0.48 09:47:13 INFO - mozfile == 1.2 09:47:13 INFO - mozhttpd == 0.7 09:47:13 INFO - mozinfo == 0.9 09:47:13 INFO - mozleak == 0.1 09:47:13 INFO - mozlog == 3.4 09:47:13 INFO - moznetwork == 0.27 09:47:13 INFO - mozprocess == 0.23 09:47:13 INFO - mozprofile == 0.28 09:47:13 INFO - mozrunner == 6.12 09:47:13 INFO - mozscreenshot == 0.1 09:47:13 INFO - mozsystemmonitor == 0.3 09:47:13 INFO - moztest == 0.7 09:47:13 INFO - mozversion == 1.4 09:47:13 INFO - psutil == 3.1.1 09:47:13 INFO - requests == 1.2.3 09:47:13 INFO - wsgiref == 0.1.2 09:47:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:13 INFO - Using env: {'DISPLAY': ':0', 09:47:13 INFO - 'HOME': '/home/cltbld', 09:47:13 INFO - 'LANG': 'en_US.UTF-8', 09:47:13 INFO - 'LANGUAGE': 'en_US:en', 09:47:13 INFO - 'LOGNAME': 'cltbld', 09:47:13 INFO - 'MAIL': '/var/mail/cltbld', 09:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:13 INFO - 'MOZ_NO_REMOTE': '1', 09:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:13 INFO - 'NO_EM_RESTART': '1', 09:47:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:13 INFO - 'PWD': '/builds/slave/test', 09:47:13 INFO - 'SHELL': '/bin/bash', 09:47:13 INFO - 'SHLVL': '1', 09:47:13 INFO - 'TERM': 'linux', 09:47:13 INFO - 'TMOUT': '86400', 09:47:13 INFO - 'USER': 'cltbld', 09:47:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:13 INFO - '_': '/tools/buildbot/bin/python'} 09:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:13 INFO - Cleaning up... 09:47:13 INFO - Return code: 0 09:47:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f064e9f3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2407790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:13 INFO - Using env: {'DISPLAY': ':0', 09:47:13 INFO - 'HOME': '/home/cltbld', 09:47:13 INFO - 'LANG': 'en_US.UTF-8', 09:47:13 INFO - 'LANGUAGE': 'en_US:en', 09:47:13 INFO - 'LOGNAME': 'cltbld', 09:47:13 INFO - 'MAIL': '/var/mail/cltbld', 09:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:13 INFO - 'MOZ_NO_REMOTE': '1', 09:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:13 INFO - 'NO_EM_RESTART': '1', 09:47:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:13 INFO - 'PWD': '/builds/slave/test', 09:47:13 INFO - 'SHELL': '/bin/bash', 09:47:13 INFO - 'SHLVL': '1', 09:47:13 INFO - 'TERM': 'linux', 09:47:13 INFO - 'TMOUT': '86400', 09:47:13 INFO - 'USER': 'cltbld', 09:47:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:13 INFO - '_': '/tools/buildbot/bin/python'} 09:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:14 INFO - Cleaning up... 09:47:14 INFO - Return code: 0 09:47:14 INFO - Running post-action listener: _resource_record_post_action 09:47:14 INFO - Running post-action listener: _start_resource_monitoring 09:47:14 INFO - Starting resource monitoring. 09:47:14 INFO - [mozharness: 2016-09-26 16:47:14.157448Z] Finished create-virtualenv step (success) 09:47:14 INFO - [mozharness: 2016-09-26 16:47:14.157951Z] Running install step. 09:47:14 INFO - Running pre-action listener: _resource_record_pre_action 09:47:14 INFO - Running main action method: install 09:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:14 INFO - Reading from file tmpfile_stdout 09:47:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:14 INFO - Reading from file tmpfile_stdout 09:47:14 INFO - Output received: 09:47:14 INFO - Usage: mozinstall [options] installer 09:47:14 INFO - Options: 09:47:14 INFO - -h, --help show this help message and exit 09:47:14 INFO - -d DEST, --destination=DEST 09:47:14 INFO - Directory to install application into. [default: 09:47:14 INFO - "/builds/slave/test"] 09:47:14 INFO - --app=APP Application being installed. [default: firefox] 09:47:14 INFO - mkdir: /builds/slave/test/build/application 09:47:14 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'] 09:47:14 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 09:47:31 INFO - Reading from file tmpfile_stdout 09:47:31 INFO - Output received: 09:47:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:47:31 INFO - Running post-action listener: _resource_record_post_action 09:47:31 INFO - [mozharness: 2016-09-26 16:47:31.444964Z] Finished install step (success) 09:47:31 INFO - [mozharness: 2016-09-26 16:47:31.445238Z] Running run-tests step. 09:47:31 INFO - Running pre-action listener: _resource_record_pre_action 09:47:31 INFO - Running main action method: run_tests 09:47:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:47:31 INFO - Python 2.7.3 09:47:31 INFO - Return code: 0 09:47:31 INFO - grabbing minidump binary from tooltool 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:31 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 0x2404930>, '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 0x2405830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2405cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:47:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:47:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:47:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:47:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:47:31 INFO - Return code: 0 09:47:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:47:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:47:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:47:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:47:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:47:31 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:47:31 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:47:31 INFO - Using env: {'DISPLAY': ':0', 09:47:31 INFO - 'HOME': '/home/cltbld', 09:47:31 INFO - 'LANG': 'en_US.UTF-8', 09:47:31 INFO - 'LANGUAGE': 'en_US:en', 09:47:31 INFO - 'LOGNAME': 'cltbld', 09:47:31 INFO - 'MAIL': '/var/mail/cltbld', 09:47:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:47:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:31 INFO - 'MOZ_NO_REMOTE': '1', 09:47:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:47:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:47:31 INFO - 'NO_EM_RESTART': '1', 09:47:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:31 INFO - 'PWD': '/builds/slave/test', 09:47:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:47:31 INFO - 'SHELL': '/bin/bash', 09:47:31 INFO - 'SHLVL': '1', 09:47:31 INFO - 'TERM': 'linux', 09:47:31 INFO - 'TMOUT': '86400', 09:47:31 INFO - 'USER': 'cltbld', 09:47:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998', 09:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:31 INFO - '_': '/tools/buildbot/bin/python'} 09:47:31 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/1474896600/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:47:31 INFO - mozversion application_buildid: 20160926063000 09:47:31 INFO - mozversion application_changeset: c55bcb7c777ea09431b4d16903ed079ae5632648 09:47:31 INFO - mozversion application_display_name: Nightly 09:47:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:47:31 INFO - mozversion application_name: Firefox 09:47:31 INFO - mozversion application_remotingname: firefox 09:47:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:47:31 INFO - mozversion application_vendor: Mozilla 09:47:31 INFO - mozversion application_version: 52.0a1 09:47:31 INFO - mozversion platform_buildid: 20160926063000 09:47:31 INFO - mozversion platform_changeset: c55bcb7c777ea09431b4d16903ed079ae5632648 09:47:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:47:31 INFO - mozversion platform_version: 52.0a1 09:47:31 INFO - using testdate: 1474908451 09:47:31 INFO - actual date: 1474908451 09:47:31 INFO - starting webserver on 'localhost:42796' 09:47:31 INFO - SUITE-START | Running 2 tests 09:47:31 INFO - TEST-START | dromaeo_css 09:47:31 INFO - operating with platform_type : linux_ 09:47:31 INFO - Initialising browser for dromaeo_css test... 09:47:31 INFO - TEST-INFO | started process 22865 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFSIYoC/profile http://localhost:42796/getInfo.html) 09:47:35 INFO - PROCESS | 22865 | __metrics Screen width/height:1600/1200 09:47:35 INFO - PROCESS | 22865 | colorDepth:24 09:47:35 INFO - PROCESS | 22865 | Browser inner width/height: 1024/697 09:47:35 INFO - PROCESS | 22865 | __metrics 09:47:36 INFO - PROCESS | 22865 | 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" 09:47:36 INFO - PROCESS | 22865 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:47:37 INFO - PROCESS | 22865 | console.error: PushService: 09:47:37 INFO - PROCESS | 22865 | stateChangeProcessEnqueue: Error transitioning state 09:47:37 INFO - PROCESS | 22865 | UnknownError 09:47:37 INFO - TEST-INFO | 22865: exit 0 09:47:37 INFO - Browser initialized. 09:47:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:37 INFO - TEST-INFO | started process 22946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFSIYoC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:49 INFO - PROCESS | 22946 | RSS: Main: 173666304 09:47:49 INFO - PROCESS | 22946 | 09:48:41 INFO - PROCESS | 22946 | 1474908521137 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:50:50 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42796/tests/dromaeo/cssquery-ext.html) 09:50:51 INFO - PROCESS | 22946 | RSS: Main: 280563712 09:50:51 INFO - PROCESS | 22946 | 09:53:54 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-ext.html (next: http://localhost:42796/tests/dromaeo/cssquery-jquery.html) 09:53:54 INFO - PROCESS | 22946 | RSS: Main: 274341888 09:53:54 INFO - PROCESS | 22946 | 09:56:56 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42796/tests/dromaeo/cssquery-mootools.html) 09:56:57 INFO - PROCESS | 22946 | RSS: Main: 215777280 09:56:57 INFO - PROCESS | 22946 | 09:59:59 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42796/tests/dromaeo/cssquery-prototype.html) 09:59:59 INFO - PROCESS | 22946 | RSS: Main: 215281664 09:59:59 INFO - PROCESS | 22946 | 10:03:02 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42796/tests/dromaeo/cssquery-yui.html) 10:03:03 INFO - PROCESS | 22946 | RSS: Main: 215605248 10:03:03 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | Cycle 1(1): loaded http://localhost:42796/tests/dromaeo/cssquery-yui.html (next: http://localhost:42796/tests/dromaeo/cssquery-dojo.html) 10:06:06 INFO - PROCESS | 22946 | RSS: Main: 290111488 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | __start_tp_report 10:06:06 INFO - PROCESS | 22946 | _x_x_mozilla_page_load 10:06:06 INFO - PROCESS | 22946 | _x_x_mozilla_page_load_details 10:06:06 INFO - PROCESS | 22946 | |i|pagename|runs| 10:06:06 INFO - PROCESS | 22946 | |0;dojo.html;3045.923851903702;3086.6141732283477;3092.134202423322;3127.3432579158375;3141.481655526838;5224.164133738603;5286;5296.046711591912;5423.050966080935;5484.177373393988;5329.094054010818;5344.679319240849;5351.117065684167;5351.919721204175;5381.0852155133625;4827.517248275162;4918.950810491903;4918.975405122969;4930.285108659242;5026.698398096126;4980.900381992358;5040.571551418134;5041.949580504203;5050.090983622947;5052.696838189702;4795.352627395292;4821.035792841446;4849.248366503198;4858.222684370484;5011.298418221452;4902.068606964665;4907.386576677915;4973.602111831081;4982.302477653131;4987.576056035241;4841.443234028096;4856.660033797635;4878.097551952865;4884.218525036013;4923;5240.68555886648;5287.233351164098;5332.600054995852;5349.7057661828585;5407.756650950717;5751.108578170349;5756.222909907193;5759.740811663485;5759.8560035999435;5765.596408251424;5768.7692492300675;5795.9420405796245;5796.043652797283;5798.565107616945;5798.9420105799245;5253.605979551549;5304.389995150607;5305.389880163753;5362.115250983583;5424.91862622057;5779.653220806681;5784.768609255667;5798.797042103506;5849.356570777211;5859.941400586024;5575.080111781592;5578.637388569811;5579.7489112990015;5581.2186293918885;5582.27430434041;5604.495595396434;5634.549236061105;5645.491905728422;5646.632968857011;5658.745356458887;5660.320761508646;5703.372629010805;5721;5744.425557444222;5806.1581070744505;5747.540196784247;5752;5756.0502517084215;5756.079027355687;5756.7121643917635;4348.326009468537;4443.111377724468;4447.2439685253585;4491.393661855673;4491.685582009228;3920.313945059603;3923.7645741255615;3942.2312649033165;3947.486826712509;4028.657564107025;5477.835664930059;5478.671279723229;5487.341519017744;5488.53347465462;5488.862778430492;5845.240118784531;5847.1229315603;5854.063349864001;5857.15071314657;5925.259342582177;5311.123664595376;5357.4106848246665;5375.973120134374;5415.10650742631;5442.102053161184;4948.703077815343;4950.6782059166035;4951.133551628523;4956.306117143603;4998.325226094431;5860.531157507475;5897.7308376032315;5904.586678932434;5920.319163296103;5977.820665380045;5630.408807075362;5631.070873306023;5709.914351284651;5746.597738158289;5773.24947756789;5933.317668468008;5943.286805583358;5950.256217972754;5990.161377407254;6011.789587364421;5653.038983372754;5666.829995100154;5668.858278543069;5669.461401166883;5691.686957217393;5540.141278101901;5622.803201887914;5700.087985922232;5778.94221057784;5786.942130578809;5595.972020139873;5667;5703.8003669873015;5766.884662306814;5779.36426993022;5691.914621280601;5694.117411801178;5695.145728140812;5700.942990570207;5701.4298570142655;5375.758090885998;5442.047641662773;5569.10894256917;5628.4090170533145;5644.153376993484;5499.725013749377;5600.60795744294;5620.775168993358;5684.175794509855;5730.5129064030025;5538.584606154474;5545.112781954868;5550.666959982414;5551.306086739158;5554.583406244467 10:06:06 INFO - PROCESS | 22946 | |1;ext.html;571.4685342631357;577.5552824325267;595.8421018430117;601.918740969969;629.1475051305481;42044.78977605112;43597.564024359745;43639.12721745567;46894.76552617232;46933.29600055993;46014.53985460144;46202.768986155024;46428.30357544639;46442.76778616111;46474;8895.911040889605;9048.819023619524;9050.094990500964;9050.728478145633;9054.547272636342;8803.867941980876;8900.109989001081;8901.06538813425;8901.73294801157;8902.86645700315;8914.50970196639;8955.19403253707;8960.327975401871;8960.462372257685;8961.865572016424;8867.467951922874;8902.243309318716;8916.598753056129;8917.509536975496;8927.910720892773;8847.29221662272;8899.110088991114;8918.375713700043;8920.152585504431;8924.687635932776;41713.374299385534;41848;42145.157096858195;42348;42426.36360454596;3200.471922132845;3260.951085733716;3428.434308339121;3461.152017755641;3515.2090779574646;31366;31628.36743265145;32432.6756732432;33271.33457330864;33899.32201355959;4124.6906482013665;4163.208990291835;4224.239636865363;4281.850135245251;4312.331588603772;340299;342197;342648;343974;345368.9999999975;34338.48492272618;35805;36066.278674426365;59061.70469147627;59780.402195977484;37951.81024094862;37957.62042379596;37987.73202421569;37994.43008354877;38009.80995095007;15311.234438278265;15438.15090170029;15454.613634659;15460.458883939009;15461.072335659672;12908.35458227085;13002.154859934075;13006.479740810451;13007.544735934196;13008.414621342063;8690.174433428814;8693.565321734015;8720.30237580992;8768.632469233271;8779.780505487413;3375.7130643895543;3388.6272510023878;3413.1637748751714;3415.692587667122;3439.5872495300723;960.8078384323023;982.2436723722693;1004.0260946881514;1032.736652153711;1071.8874518175635;29735;30632.84683576568;30765.846170769004;31045.68954310473;31555;1101.5869049106584;1103.3876198709634;1105.082781291526;1114.7347760292128;1129.88701129888;15938.760918586231;16328.265228064765;16588.917055414644;16696.666066678597;16701.749473757904;534193;534430;534764;536587;543444;57884.71057644685;58703.41296586979;58711.70644146752;58737.41262587405;58792.41207587955;1062.3731998121145;1115.2026301194685;1297.3189075735315;1334.2394834944182;1371.5885234429632;28896.566551501324;32840.835795820865;33071.33857322792;33177.50233746447;33706.8314658435;48763;53234;54479.18281225706;54581;59685;496260;500379;500640.91668082913;500767;501561;29869.402611948073;30590.388192235587;38406;41343.7932810334;43301.56698433102;42735.7863210682;46938.29592556046;47192.29211561761;47501;47519;39942;40062.79968600138;40070.39894401528;40590.39114413227;42182;38903.44386224519;39421;39799.40300895431;40940.795296024524;41825.16349673051;38817.61182388253;40727.79636101801;41814.79092604518;42112.789436052626;42312.36531451969;34978.65021349856;36342.27315453729;37096.32266192111;37123.81438092792;38014.429783553824;28200.43599127965;28296.858515708114;28298.434031318848;28580;28790.712092878803 10:06:06 INFO - PROCESS | 22946 | |2;jquery.html;354.18360678635736;397.3106659944995;400.81762797926956;401.68467752814036;419.2704693832732;12556.623301300964;19077.332293369735;19195.16096780644;19888;19991.40025799225;18263.54341141469;19760.30838920638;19894.303699370525;19947.10238039288;19968.50078748032;5783.334916484606;6047.606905551147;6049.122877182828;6057.878842423149;6062.18160548327;5692.544596432275;5837.182794408786;5859.736311865976;5865.970670146643;5868.41315868414;5774;5826.475617194452;5842.152887831262;5852.063669812831;5854.326752423462;5594.328680558338;5809.593328467009;5841.7371218295275;5866.560007999417;5888.64668119912;5195.974020129913;5235.371755389339;5241.68549887008;5245.711485868275;5246.763895624668;23359.88320058389;23539.529209415716;23713.28860134199;23777.405564860845;24213.63679544808;2371.359732872115;2420.201333559921;2536.606825941982;2639.577667573179;2784.9860750696116;14629.268536573129;14631.780523292158;14662.853371466363;14693.04495207808;14843.777343339858;1938.4378530226218;1951.2097600471832;1971.142552989454;1974.9308774192978;2018.798120187984;81342;82503.58748206281;84126.99999999939;84628.57685711594;85973.57013214893;10785.62250321235;10897.400642964554;11002.723001885239;11088.667339979653;11185.272957257752;15570.221488925736;15576.454824081104;15580.143092129814;15607;15615.375384984472;8203.384746144064;8361;8452.74641760748;8529.14708529142;8576.485410875368;7900.841983160304;8090.474119182234;8132.634031468599;8196.098429172785;8250.092489826115;4723.858284251478;4775.2359622460235;4794.568488836021;4858.489858564871;4944.085344211332;1842.8065053169491;2028.9797102029086;2048.92828750993;2056.105594066586;2059.1866212845844;879.3624622148891;914.2685851319051;975.4050029482011;986.3194395866828;1016.8865092723456;17650.470485885446;18727.90636046811;19150.425487235407;19678.70481942772;19821.008949552466;971.6307803034942;978.3982850547005;986.5856340337076;990.1187942730975;1024;10189.898101019044;10928.672139835817;10983.286086404518;11013;11057.668269951915;446272;458792;461068;469026;474605;66210;82056;83626.58186709149;84778.5761071203;87439.56280218686;975.8926518083006;988.165000574499;1002.6540843409158;1008.8486726990864;1016.2175125153738;15803.36786528526;16205;16350.509484715478;17691.823081769016;17704.91147544254;39339.60660393359;39774.801125994185;40337;40496.3925541123;40925.86112083092;53301;53955.460445395045;55190;55200.17199741927;56485;7732.342750866239;8630.482171069505;8632.179942905414;8670.653173872974;8916.19754222123;12193.512259509875;13261.66845828862;13435.932820335836;13674.31628418595;13720.588382348546;11592.1305902056;11885.405729713308;11924.403779811148;12052.758944820878;12102.75794484123;11228.719282018015;11445.882511735355;11492.36791976416;11562.595309164139;11890.405479726152;11808.527658893549;11993.700157496132;12026.939865300967;12314.384280785745;12491.063170262085;7986.84026319482;8081.272685458337;8219.260266576037;8272.958635207026;8372.372072094495;9759.868339026892;11407.665087266785;11704.239224450383;11919.165658403828;11983.34091624969 10:06:06 INFO - PROCESS | 22946 | |3;mootools.html;1131.4851742457183;1447.421031587365;1486.5911874234587;1632.6816270827194;1777.271318759309;8047.436679432442;8987.146221108993;9226.215771659401;9302.488363140033;9639.325247232697;9224.8616270756;9310.30172737044;9337.486438245895;9357.111074447934;9402.764930876732;2401.6037353836596;2423.5516429460517;2425.745296743843;2431.513697260546;2433.2480613957923;2356.3166681662296;2388.8088952883813;2395.125779090628;2406.2300063979533;2411.602085655865;2355.693759811231;2364.432536191319;2368.384220102777;2436.2082323244927;2437.183543512925;2172.532905425325;2196.3410976707005;2199.2192771566106;2201.9779802202015;2231.4532939429782;2334.8832558372183;2355.7173139223232;2357.728861180969;2359.2096647623066;2360.173939121311;9052.230560402373;9193.678221262291;9249.028851970581;9390.577424015935;9511.619535218582;5295.602829787743;5493.533049690741;5521.2270282160525;5523.033469142884;5531.170324451325;3372.5615669962995;3419.863205471778;3458.602260740022;3462.8614855405754;3525.1715846775987;1079.1097344690584;1086;1088.983665245022;1113.3765091548678;1115.1970581181536;13097.803532947013;13126.14680045794;13241.337933103307;13264.801027984684;13315.40080696371;4607.631389488833;4674.392328997209;4883.804647814119;4913.852584422473;4920.4833492483485;10168.135708464715;10197.337173083726;10200.642977495752;10219.335743176669;10223.897761022294;5544.251526044013;5560.304961879765;5561.249231353796;5571.721413929287;5572.721363931787;4370.672199585044;4507.301368287921;4954.256861470808;5036.395632524121;5182.844514664566;3805.5813860475328;3887.6501114899793;3934.567197608261;3953.5651078381356;4010.498687664042;1189.5776999165346;1201.6575276046285;1202.4048096192441;1217.9573714919936;1232.2298563397876;869.8651708985118;911.48956584313;984.2814745236018;1007.3502590828874;1008.5562352564848;5967.075103358986;6033.3664965178905;6042.184305118841;6262.499000079983;6362;490.1911845455028;492.87924458507894;494.8911239527298;496.3051727581343;498.39445074234976;4180.268453020709;4189.853355132555;4218.78906054696;4237.8304867805555;4241.830326786894;11183.105351571854;11753.941230293794;11769.352685602204;11771.411429428494;11774.116941229442;10917.727056823644;11445.427728613537;11454.656360309204;11637.592684256011;11667.299962002307;8316.916830831615;8502.44734092282;8503.1921967413;8503.319734421231;8509.361797865186;6261.499080073492;6263.874722505616;6281.654509002049;6378.170837791057;6504.804855854332;10701.678949631523;10737.785244294913;10788.083012943987;10864.565417383532;10904.073153782017;10435;10923.399213043358;10931.945340273247;11134.777304454028;11201.831972520582;6955.408790252885;7192.2088570258275;7193.460490463341;8217.630206640835;8397.580120994047;8019.799505012421;8390.958045209734;8776.122387761172;8899.243564297107;9175.954120229357;7829.256220659028;8004.51972881629;8035.718749843727;8225.383096267684;8342.457740246864;7947.245011723877;8025.438219324825;8068.798280043045;8144.226298501633;8171.7548473545885;7369.668364923484;7401.8149546261775;7452.627368631438;7456.440766942393;7568.243175682608;5405.4594540547205;5409.188621706776;5424.077906755782;5525.060739674184;5635.464630860061;5625.46558076982;5633.267675202197;5653.349864765604;5701.714914254354;5776.393478684678 10:06:06 INFO - PROCESS | 22946 | |4;prototype.html;332.9617094034186;333.4631243697646;342.1497578517384;347.60893994256463;348.644382729616;5288.418273989868;5290.470952904708;5300.9204861927055;5302.3106996090555;5308.097623404021;5138.203578445337;5165.612579056577;5222.216667499867;5231.816886408977;5243.187305967581;3900.8687480630583;3948.2498325318243;4113.9177216455655;4114.526829414625;4215.072684009513;4162.334026555752;4165.208610364036;4167.041580436501;4174.144300418422;4201.537830838605;4130.586941305861;4132.29750942339;4134.751914885116;4137.862087925814;4144.21259960608;4013.3177359848896;4104.589541045902;4132.462779838632;4133.979330103345;4141.958580414187;3678.988278223483;3681.44778283257;3684.8341824617955;3687.299413111527;3688.9631103689157;5073.0107629012355;5092.745362731848;5092.911906118511;5094.923576146361;5123.667846359973;4617.953820461819;4655.701971663516;4671.369365135697;4723.433188017426;4733.266343716695;5399.514043736082;5401.756920938567;5408.161734931053;5409.43200963897;5409.83770486886;4041.9797901010306;4046.372812214082;4063.837446502166;4074.4092106644666;4076.470058892325;5346.572274218053;5643.802466913638;5648.4633959773755;5675.148727690838;5709.685967271798;4000.3398589584995;4011.679065674739;4037.8384864605086;4047.1905618876344;4076.001379662002;4091;4095.52901416335;4119;4123.319652257344;4131.752094874317;4595.494495605481;4717.080169367009;4717.858464246078;4742.099001189763;4754.857354279377;5498.130635583921;5500.779968801283;5505.862353441116;5548.225887056472;5550.333959924754;1399.9720005599831;1418.5106138382241;1422.7154569086224;1428.6642638979831;1433.6702558411416;886.866969954512;908.5411867007119;922.9432300016443;934.2619330728784;944.918068811221;5163.509466600667;5164.457731938168;5312.123499622519;5355.946440535623;5365.543928766098;5547.334319881559;5555.194496797942;5558.583106267046;5593.916091258634;5619.971900140555;5179.3426103647225;5243.726678644109;5283.920741188886;5314.681119132864;5318.361796584435;2959.4820906341306;2977.1663934098488;2981.8509074546187;2985.1044686593937;2986.25343664084;5733.140028995684;5786.681732504752;5787.363390027093;5789.739461724233;5791.623544469596;5546.223528705985;5686.914696279559;5724.227229323988;5738.594044459134;5745.569082318842;5738.913916291259;5748.338941021752;5752.856178595569;5786.537077033827;5801.36185019639;5347.411784703757;5562.5549956002615;5595.244641973281;5629.211910332475;5647.983362994701;5499.532539734168;5543.307086614173;5546.7781288748;5552.306546503355;5560.332760068818;5732.140178973187;5753.568482363756;5769.451902069297;5807.738651760766;5818.970905145447;3855.7300988930506;4040.0909795295825;4043.636072753349;4052.63526282636;4093.2222877653153;5616.652003519208;5618.2134501170685;5618.2415373924;5639.7744090237575;5653.943460565341;5505.788726480168;5520.420355862577;5527.861803454785;5534.1975413564005;5535.7785688572;5262.973720124692;5411.540019098421;5444.564434845282;5459.5632349412745;5465.426130256425;5371.838844834661;5378.515933565998;5389.461053894579;5395.487428694268;5409.5672346213;5265.499332690165;5280.1815718563685;5373.543248823894;5442.14966911252;5465.754041068241;5327.280817089643;5350.250964864844;5402.7303583658595;5413.078357183143;5418.349798024262 10:06:06 INFO - PROCESS | 22946 | |5;yui.html;737.8155461134713;746.0338861174778;767.9308862202402;795.9522428654282;805.9460911614574;14746;16398.7540186897;16455.341786328547;16557.420490282842;16700.832991670093;15300.617484562892;15420.228988550556;15433.922830385833;15447.5365739028;15449.22753862308;430.03028171473284;431.06243919475173;795.4193438789702;811.5130921447144;821.0311832038193;759.741687826139;802.2458888644685;802.9759107226793;816.808050108224;817.7996390884242;865.2040123086775;867.9349048821332;868.0494858130337;869.0570730757136;871.5903525343078;445.6969260902585;862.8015556422058;863.2834747159874;863.6977058029671;863.745195167424;332.8369099141427;588.9941100588982;622.919020527333;626.890294198518;628.5788521690479;15291.464798731991;15296.694066118727;15312.387504499806;15316;15320.616984575363;13298.202107873558;13464.124831885994;13660.795088073686;13940.571235524101;14013.299335033207;12126.27242365452;12146.635600931897;12177.817332740016;12236;12274.570390036395;4402.933955990663;4428.601425871655;4457;4483.170613436525;4484.551544845522;82973;83060.16939830585;83677.58161209215;83910;83923.16076839276;10804.405757683402;10897.618583349704;11020.283681560673;11439.828402573969;11485;20785.064672089793;20919.58160836805;21108.894455527625;21315.25396611111;21382.14471421157;7779.299863012356;7873.763787086396;7961.1242763295995;7963.721269755647;8020.71927482547;4457.086297309044;4479.7984090716;4550.112728018004;4681.274402467623;4698.36572062774;3131.123285480069;3206.150370151912;3211.2132527530903;3222.8710851565675;3227.1609381560493;578.6238944685904;598.7964092208583;600.4445887554083;608.3946473259135;625.3683779382765;651.4137276451232;655.6852710698892;659.947204223661;668.1347654787062;679.0222080204489;8819.250363719028;8974.058894128972;9036.86798977516;9144.588493517807;9401.341906066646;591.2786400591272;608.6378604730179;629.2386212682613;640.2349192714661;647.899575565788;4160.667146628323;4377.430933978626;4390.407295015132;4466.196084704719;4477.753723545171;95374.52312738531;113610.43194783974;128312;134444.32777836244;135772;93279;107617.46191268993;109530;112704.43647781709;113891;56875.43124568784;59624;92449.53775231216;94581;95308.52345738227;7846.725364612211;8144.1448647893485;8184.304334131665;8289.585520723818;8319.83360332778;55232.72383638056;55842;56049.71975140261;56831.43168568261;57482.42517574771;79313.20686793058;83145.58427207825;86208.56895715481;88100;89660;10712.73218169552;10915.290506117077;10974.067204287405;11209.607663731731;11588.072954163477;11364.54541818318;11657.708557286136;11750.23623464472;11765.705857353634;11956.70108247266;10143.89856101459;10754.24720269605;10840;10976.451177440937;11425.60010399632;12629.810552841898;12720.300383478627;13573.59279221625;13717.245551494763;13933.303334833421;11572.59495917639;12106.394680266128;12395.504179832706;12421.068419868727;12462.626121216377;10666.253362264568;10741.89258107409;10847.294925830112;10924.399158046066;12059.81910271329;10264.281500295207;11016.834747478633;11299.209055366375;11495.367754773568;11572.18994670365 10:06:06 INFO - PROCESS | 22946 | __end_tp_report 10:06:06 INFO - PROCESS | 22946 | __start_cc_report 10:06:06 INFO - PROCESS | 22946 | _x_x_mozilla_cycle_collect,1094 10:06:06 INFO - PROCESS | 22946 | __end_cc_report 10:06:06 INFO - PROCESS | 22946 | __startTimestamp1474909566295__endTimestamp 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | ------- Summary: start ------- 10:06:06 INFO - PROCESS | 22946 | Number of tests: 6 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#0] dojo.html Cycles:165 Average:5342.06 Median:5551.31 stddev:597.17 (10.8%) stddev-sans-first:571.19 10:06:06 INFO - PROCESS | 22946 | Values: 3045.9 3086.6 3092.1 3127.3 3141.5 5224.2 5286.0 5296.0 5423.1 5484.2 5329.1 5344.7 5351.1 5351.9 5381.1 4827.5 4919.0 4919.0 4930.3 5026.7 4980.9 5040.6 5041.9 5050.1 5052.7 4795.4 4821.0 4849.2 4858.2 5011.3 4902.1 4907.4 4973.6 4982.3 4987.6 4841.4 4856.7 4878.1 4884.2 4923.0 5240.7 5287.2 5332.6 5349.7 5407.8 5751.1 5756.2 5759.7 5759.9 5765.6 5768.8 5795.9 5796.0 5798.6 5798.9 5253.6 5304.4 5305.4 5362.1 5424.9 5779.7 5784.8 5798.8 5849.4 5859.9 5575.1 5578.6 5579.7 5581.2 5582.3 5604.5 5634.5 5645.5 5646.6 5658.7 5660.3 5703.4 5721.0 5744.4 5806.2 5747.5 5752.0 5756.1 5756.1 5756.7 4348.3 4443.1 4447.2 4491.4 4491.7 3920.3 3923.8 3942.2 3947.5 4028.7 5477.8 5478.7 5487.3 5488.5 5488.9 5845.2 5847.1 5854.1 5857.2 5925.3 5311.1 5357.4 5376.0 5415.1 5442.1 4948.7 4950.7 4951.1 4956.3 4998.3 5860.5 5897.7 5904.6 5920.3 5977.8 5630.4 5631.1 5709.9 5746.6 5773.2 5933.3 5943.3 5950.3 5990.2 6011.8 5653.0 5666.8 5668.9 5669.5 5691.7 5540.1 5622.8 5700.1 5778.9 5786.9 5596.0 5667.0 5703.8 5766.9 5779.4 5691.9 5694.1 5695.1 5700.9 5701.4 5375.8 5442.0 5569.1 5628.4 5644.2 5499.7 5600.6 5620.8 5684.2 5730.5 5538.6 5545.1 5550.7 5551.3 5554.6 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#1] ext.html Cycles:180 Average:60754.97 Median:22753.50 stddev:124336.96 (546.5%) stddev-sans-first:124603.65 10:06:06 INFO - PROCESS | 22946 | Values: 571.5 577.6 595.8 601.9 629.1 42044.8 43597.6 43639.1 46894.8 46933.3 46014.5 46202.8 46428.3 46442.8 46474.0 8895.9 9048.8 9050.1 9050.7 9054.5 8803.9 8900.1 8901.1 8901.7 8902.9 8914.5 8955.2 8960.3 8960.5 8961.9 8867.5 8902.2 8916.6 8917.5 8927.9 8847.3 8899.1 8918.4 8920.2 8924.7 41713.4 41848.0 42145.2 42348.0 42426.4 3200.5 3261.0 3428.4 3461.2 3515.2 31366.0 31628.4 32432.7 33271.3 33899.3 4124.7 4163.2 4224.2 4281.9 4312.3 340299.0 342197.0 342648.0 343974.0 345369.0 34338.5 35805.0 36066.3 59061.7 59780.4 37951.8 37957.6 37987.7 37994.4 38009.8 15311.2 15438.2 15454.6 15460.5 15461.1 12908.4 13002.2 13006.5 13007.5 13008.4 8690.2 8693.6 8720.3 8768.6 8779.8 3375.7 3388.6 3413.2 3415.7 3439.6 960.8 982.2 1004.0 1032.7 1071.9 29735.0 30632.8 30765.8 31045.7 31555.0 1101.6 1103.4 1105.1 1114.7 1129.9 15938.8 16328.3 16588.9 16696.7 16701.7 534193.0 534430.0 534764.0 536587.0 543444.0 57884.7 58703.4 58711.7 58737.4 58792.4 1062.4 1115.2 1297.3 1334.2 1371.6 28896.6 32840.8 33071.3 33177.5 33706.8 48763.0 53234.0 54479.2 54581.0 59685.0 496260.0 500379.0 500640.9 500767.0 501561.0 29869.4 30590.4 38406.0 41343.8 43301.6 42735.8 46938.3 47192.3 47501.0 47519.0 39942.0 40062.8 40070.4 40590.4 42182.0 38903.4 39421.0 39799.4 40940.8 41825.2 38817.6 40727.8 41814.8 42112.8 42312.4 34978.7 36342.3 37096.3 37123.8 38014.4 28200.4 28296.9 28298.4 28580.0 28790.7 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#2] jquery.html Cycles:180 Average:27988.18 Median:19868.46 stddev:76104.09 (383.0%) stddev-sans-first:76289.30 10:06:06 INFO - PROCESS | 22946 | Values: 354.2 397.3 400.8 401.7 419.3 12556.6 19077.3 19195.2 19888.0 19991.4 18263.5 19760.3 19894.3 19947.1 19968.5 5783.3 6047.6 6049.1 6057.9 6062.2 5692.5 5837.2 5859.7 5866.0 5868.4 5774.0 5826.5 5842.2 5852.1 5854.3 5594.3 5809.6 5841.7 5866.6 5888.6 5196.0 5235.4 5241.7 5245.7 5246.8 23359.9 23539.5 23713.3 23777.4 24213.6 2371.4 2420.2 2536.6 2639.6 2785.0 14629.3 14631.8 14662.9 14693.0 14843.8 1938.4 1951.2 1971.1 1974.9 2018.8 81342.0 82503.6 84127.0 84628.6 85973.6 10785.6 10897.4 11002.7 11088.7 11185.3 15570.2 15576.5 15580.1 15607.0 15615.4 8203.4 8361.0 8452.7 8529.1 8576.5 7900.8 8090.5 8132.6 8196.1 8250.1 4723.9 4775.2 4794.6 4858.5 4944.1 1842.8 2029.0 2048.9 2056.1 2059.2 879.4 914.3 975.4 986.3 1016.9 17650.5 18727.9 19150.4 19678.7 19821.0 971.6 978.4 986.6 990.1 1024.0 10189.9 10928.7 10983.3 11013.0 11057.7 446272.0 458792.0 461068.0 469026.0 474605.0 66210.0 82056.0 83626.6 84778.6 87439.6 975.9 988.2 1002.7 1008.8 1016.2 15803.4 16205.0 16350.5 17691.8 17704.9 39339.6 39774.8 40337.0 40496.4 40925.9 53301.0 53955.5 55190.0 55200.2 56485.0 7732.3 8630.5 8632.2 8670.7 8916.2 12193.5 13261.7 13435.9 13674.3 13720.6 11592.1 11885.4 11924.4 12052.8 12102.8 11228.7 11445.9 11492.4 11562.6 11890.4 11808.5 11993.7 12026.9 12314.4 12491.1 7986.8 8081.3 8219.3 8273.0 8372.4 9759.9 11407.7 11704.2 11919.2 11983.3 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#3] mootools.html Cycles:180 Average:6028.98 Median:4779.10 stddev:3523.71 (73.7%) stddev-sans-first:3514.36 10:06:06 INFO - PROCESS | 22946 | Values: 1131.5 1447.4 1486.6 1632.7 1777.3 8047.4 8987.1 9226.2 9302.5 9639.3 9224.9 9310.3 9337.5 9357.1 9402.8 2401.6 2423.6 2425.7 2431.5 2433.2 2356.3 2388.8 2395.1 2406.2 2411.6 2355.7 2364.4 2368.4 2436.2 2437.2 2172.5 2196.3 2199.2 2202.0 2231.5 2334.9 2355.7 2357.7 2359.2 2360.2 9052.2 9193.7 9249.0 9390.6 9511.6 5295.6 5493.5 5521.2 5523.0 5531.2 3372.6 3419.9 3458.6 3462.9 3525.2 1079.1 1086.0 1089.0 1113.4 1115.2 13097.8 13126.1 13241.3 13264.8 13315.4 4607.6 4674.4 4883.8 4913.9 4920.5 10168.1 10197.3 10200.6 10219.3 10223.9 5544.3 5560.3 5561.2 5571.7 5572.7 4370.7 4507.3 4954.3 5036.4 5182.8 3805.6 3887.7 3934.6 3953.6 4010.5 1189.6 1201.7 1202.4 1218.0 1232.2 869.9 911.5 984.3 1007.4 1008.6 5967.1 6033.4 6042.2 6262.5 6362.0 490.2 492.9 494.9 496.3 498.4 4180.3 4189.9 4218.8 4237.8 4241.8 11183.1 11753.9 11769.4 11771.4 11774.1 10917.7 11445.4 11454.7 11637.6 11667.3 8316.9 8502.4 8503.2 8503.3 8509.4 6261.5 6263.9 6281.7 6378.2 6504.8 10701.7 10737.8 10788.1 10864.6 10904.1 10435.0 10923.4 10931.9 11134.8 11201.8 6955.4 7192.2 7193.5 8217.6 8397.6 8019.8 8391.0 8776.1 8899.2 9176.0 7829.3 8004.5 8035.7 8225.4 8342.5 7947.2 8025.4 8068.8 8144.2 8171.8 7369.7 7401.8 7452.6 7456.4 7568.2 5405.5 5409.2 5424.1 5525.1 5635.5 5625.5 5633.3 5653.3 5701.7 5776.4 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#4] prototype.html Cycles:180 Average:4624.23 Median:5301.62 stddev:1341.52 (25.3%) stddev-sans-first:1306.05 10:06:06 INFO - PROCESS | 22946 | Values: 333.0 333.5 342.1 347.6 348.6 5288.4 5290.5 5300.9 5302.3 5308.1 5138.2 5165.6 5222.2 5231.8 5243.2 3900.9 3948.2 4113.9 4114.5 4215.1 4162.3 4165.2 4167.0 4174.1 4201.5 4130.6 4132.3 4134.8 4137.9 4144.2 4013.3 4104.6 4132.5 4134.0 4142.0 3679.0 3681.4 3684.8 3687.3 3689.0 5073.0 5092.7 5092.9 5094.9 5123.7 4618.0 4655.7 4671.4 4723.4 4733.3 5399.5 5401.8 5408.2 5409.4 5409.8 4042.0 4046.4 4063.8 4074.4 4076.5 5346.6 5643.8 5648.5 5675.1 5709.7 4000.3 4011.7 4037.8 4047.2 4076.0 4091.0 4095.5 4119.0 4123.3 4131.8 4595.5 4717.1 4717.9 4742.1 4754.9 5498.1 5500.8 5505.9 5548.2 5550.3 1400.0 1418.5 1422.7 1428.7 1433.7 886.9 908.5 922.9 934.3 944.9 5163.5 5164.5 5312.1 5355.9 5365.5 5547.3 5555.2 5558.6 5593.9 5620.0 5179.3 5243.7 5283.9 5314.7 5318.4 2959.5 2977.2 2981.9 2985.1 2986.3 5733.1 5786.7 5787.4 5789.7 5791.6 5546.2 5686.9 5724.2 5738.6 5745.6 5738.9 5748.3 5752.9 5786.5 5801.4 5347.4 5562.6 5595.2 5629.2 5648.0 5499.5 5543.3 5546.8 5552.3 5560.3 5732.1 5753.6 5769.5 5807.7 5819.0 3855.7 4040.1 4043.6 4052.6 4093.2 5616.7 5618.2 5618.2 5639.8 5653.9 5505.8 5520.4 5527.9 5534.2 5535.8 5263.0 5411.5 5444.6 5459.6 5465.4 5371.8 5378.5 5389.5 5395.5 5409.6 5265.5 5280.2 5373.5 5442.1 5465.8 5327.3 5350.3 5402.7 5413.1 5418.3 10:06:06 INFO - PROCESS | 22946 | 10:06:06 INFO - PROCESS | 22946 | [#5] yui.html Cycles:180 Average:21336.45 Median:2437.15 stddev:32224.60 (1322.2%) stddev-sans-first:32277.89 10:06:06 INFO - PROCESS | 22946 | Values: 737.8 746.0 767.9 796.0 805.9 14746.0 16398.8 16455.3 16557.4 16700.8 15300.6 15420.2 15433.9 15447.5 15449.2 430.0 431.1 795.4 811.5 821.0 759.7 802.2 803.0 816.8 817.8 865.2 867.9 868.0 869.1 871.6 445.7 862.8 863.3 863.7 863.7 332.8 589.0 622.9 626.9 628.6 15291.5 15296.7 15312.4 15316.0 15320.6 13298.2 13464.1 13660.8 13940.6 14013.3 12126.3 12146.6 12177.8 12236.0 12274.6 4402.9 4428.6 4457.0 4483.2 4484.6 82973.0 83060.2 83677.6 83910.0 83923.2 10804.4 10897.6 11020.3 11439.8 11485.0 20785.1 20919.6 21108.9 21315.3 21382.1 7779.3 7873.8 7961.1 7963.7 8020.7 4457.1 4479.8 4550.1 4681.3 4698.4 3131.1 3206.2 3211.2 3222.9 3227.2 578.6 598.8 600.4 608.4 625.4 651.4 655.7 659.9 668.1 679.0 8819.3 8974.1 9036.9 9144.6 9401.3 591.3 608.6 629.2 640.2 647.9 4160.7 4377.4 4390.4 4466.2 4477.8 95374.5 113610.4 128312.0 134444.3 135772.0 93279.0 107617.5 109530.0 112704.4 113891.0 56875.4 59624.0 92449.5 94581.0 95308.5 7846.7 8144.1 8184.3 8289.6 8319.8 55232.7 55842.0 56049.7 56831.4 57482.4 79313.2 83145.6 86208.6 88100.0 89660.0 10712.7 10915.3 10974.1 11209.6 11588.1 11364.5 11657.7 11750.2 11765.7 11956.7 10143.9 10754.2 10840.0 10976.5 11425.6 12629.8 12720.3 13573.6 13717.2 13933.3 11572.6 12106.4 12395.5 12421.1 12462.6 10666.3 10741.9 10847.3 10924.4 12059.8 10264.3 11016.8 11299.2 11495.4 11572.2 10:06:06 INFO - PROCESS | 22946 | -------- Summary: end -------- 10:06:06 INFO - PROCESS | 22946 | 10:06:07 INFO - TEST-INFO | 22946: exit 0 10:06:07 INFO - TEST-OK | dromaeo_css | took 1115534ms 10:06:07 INFO - TEST-START | kraken 10:06:07 INFO - operating with platform_type : linux_ 10:06:07 INFO - Initialising browser for kraken test... 10:06:07 INFO - TEST-INFO | started process 23253 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpshMYvY/profile http://localhost:42796/getInfo.html) 10:06:11 INFO - PROCESS | 23253 | __metrics Screen width/height:1600/1200 10:06:11 INFO - PROCESS | 23253 | colorDepth:24 10:06:11 INFO - PROCESS | 23253 | Browser inner width/height: 1024/697 10:06:11 INFO - PROCESS | 23253 | __metrics 10:06:11 INFO - PROCESS | 23253 | 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" 10:06:11 INFO - PROCESS | 23253 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:12 INFO - PROCESS | 23253 | console.error: PushService: 10:06:12 INFO - PROCESS | 23253 | stateChangeProcessEnqueue: Error transitioning state 10:06:12 INFO - PROCESS | 23253 | UnknownError 10:06:12 INFO - TEST-INFO | 23253: exit 0 10:06:12 INFO - Browser initialized. 10:06:12 INFO - Running cycle 1/1 for kraken test... 10:06:13 INFO - TEST-INFO | started process 23331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpshMYvY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:25 INFO - PROCESS | 23331 | RSS: Main: 173510656 10:06:25 INFO - PROCESS | 23331 | 10:06:27 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42796/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:28 INFO - PROCESS | 23331 | RSS: Main: 232898560 10:06:28 INFO - PROCESS | 23331 | 10:06:31 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42796/tests/kraken/driver.html?testName=audio-dft) 10:06:31 INFO - PROCESS | 23331 | RSS: Main: 217223168 10:06:31 INFO - PROCESS | 23331 | 10:06:34 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42796/tests/kraken/driver.html?testName=audio-fft) 10:06:35 INFO - PROCESS | 23331 | RSS: Main: 298610688 10:06:35 INFO - PROCESS | 23331 | 10:06:37 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42796/tests/kraken/driver.html?testName=audio-oscillator) 10:06:38 INFO - PROCESS | 23331 | RSS: Main: 208191488 10:06:38 INFO - PROCESS | 23331 | 10:06:40 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42796/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:06:40 INFO - PROCESS | 23331 | RSS: Main: 213225472 10:06:40 INFO - PROCESS | 23331 | 10:06:43 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42796/tests/kraken/driver.html?testName=imaging-darkroom) 10:06:44 INFO - PROCESS | 23331 | RSS: Main: 249942016 10:06:44 INFO - PROCESS | 23331 | 10:06:47 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42796/tests/kraken/driver.html?testName=imaging-desaturate) 10:06:48 INFO - PROCESS | 23331 | RSS: Main: 248832000 10:06:48 INFO - PROCESS | 23331 | 10:06:51 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42796/tests/kraken/driver.html?testName=json-parse-financial) 10:06:51 INFO - PROCESS | 23331 | RSS: Main: 248786944 10:06:51 INFO - PROCESS | 23331 | 10:06:53 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42796/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:06:53 INFO - PROCESS | 23331 | RSS: Main: 210984960 10:06:53 INFO - PROCESS | 23331 | 10:06:55 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:06:56 INFO - PROCESS | 23331 | RSS: Main: 218075136 10:06:56 INFO - PROCESS | 23331 | 10:06:58 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:06:59 INFO - PROCESS | 23331 | RSS: Main: 223588352 10:06:59 INFO - PROCESS | 23331 | 10:07:01 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:02 INFO - PROCESS | 23331 | RSS: Main: 224124928 10:07:02 INFO - PROCESS | 23331 | 10:07:04 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:05 INFO - PROCESS | 23331 | RSS: Main: 222031872 10:07:05 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | Cycle 1(1): loaded http://localhost:42796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42796/tests/kraken/driver.html?testName=ai-astar) 10:07:07 INFO - PROCESS | 23331 | RSS: Main: 273223680 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | __start_tp_report 10:07:07 INFO - PROCESS | 23331 | _x_x_mozilla_page_load 10:07:07 INFO - PROCESS | 23331 | _x_x_mozilla_page_load_details 10:07:07 INFO - PROCESS | 23331 | |i|pagename|runs| 10:07:07 INFO - PROCESS | 23331 | |0;ai-astar;109;112;110;110;111;111;111;111;110;110 10:07:07 INFO - PROCESS | 23331 | |1;audio-beat-detection;151;125;118;171;118;124;118;120;176;117 10:07:07 INFO - PROCESS | 23331 | |2;audio-dft;162;160;160;160;162;160;161;161;160;161 10:07:07 INFO - PROCESS | 23331 | |3;audio-fft;76;76;77;102;76;76;76;79;110;76 10:07:07 INFO - PROCESS | 23331 | |4;audio-oscillator;82;81;116;82;82;81;102;81;82;112 10:07:07 INFO - PROCESS | 23331 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;116;117;116 10:07:07 INFO - PROCESS | 23331 | |6;imaging-darkroom;146;146;147;147;146;147;146;147;146;147 10:07:07 INFO - PROCESS | 23331 | |7;imaging-desaturate;95;96;95;95;96;95;95;95;96;95 10:07:07 INFO - PROCESS | 23331 | |8;json-parse-financial;50;50;57;50;51;50;51;51;50;52 10:07:07 INFO - PROCESS | 23331 | |9;json-stringify-tinderbox;60;59;60;59;60;60;60;60;60;60 10:07:07 INFO - PROCESS | 23331 | |10;stanford-crypto-aes;84;79;88;79;78;78;80;79;79;85 10:07:07 INFO - PROCESS | 23331 | |11;stanford-crypto-ccm;125;124;129;131;119;125;152;122;158;137 10:07:07 INFO - PROCESS | 23331 | |12;stanford-crypto-pbkdf2;167;169;162;167;166;164;167;160;159;166 10:07:07 INFO - PROCESS | 23331 | |13;stanford-crypto-sha256-iterative;65;66;65;64;64;64;64;64;63;65 10:07:07 INFO - PROCESS | 23331 | __end_tp_report 10:07:07 INFO - PROCESS | 23331 | __start_cc_report 10:07:07 INFO - PROCESS | 23331 | _x_x_mozilla_cycle_collect,1597 10:07:07 INFO - PROCESS | 23331 | __end_cc_report 10:07:07 INFO - PROCESS | 23331 | __startTimestamp1474909627447__endTimestamp 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | ------- Summary: start ------- 10:07:07 INFO - PROCESS | 23331 | Number of tests: 14 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:0.85 (0.8%) stddev-sans-first:0.71 10:07:07 INFO - PROCESS | 23331 | Values: 109.0 112.0 110.0 110.0 111.0 111.0 111.0 111.0 110.0 110.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:124.50 stddev:23.23 (18.7%) stddev-sans-first:23.79 10:07:07 INFO - PROCESS | 23331 | Values: 151.0 125.0 118.0 171.0 118.0 124.0 118.0 120.0 176.0 117.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 10:07:07 INFO - PROCESS | 23331 | Values: 162.0 160.0 160.0 160.0 162.0 160.0 161.0 161.0 160.0 161.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:12.62 (16.6%) stddev-sans-first:13.17 10:07:07 INFO - PROCESS | 23331 | Values: 76.0 76.0 77.0 102.0 76.0 76.0 76.0 79.0 110.0 76.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:81.50 stddev:14.15 (17.4%) stddev-sans-first:14.71 10:07:07 INFO - PROCESS | 23331 | Values: 82.0 81.0 116.0 82.0 82.0 81.0 102.0 81.0 82.0 112.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:07:07 INFO - PROCESS | 23331 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 116.0 117.0 116.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 10:07:07 INFO - PROCESS | 23331 | Values: 146.0 146.0 147.0 147.0 146.0 147.0 146.0 147.0 146.0 147.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:07:07 INFO - PROCESS | 23331 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:2.15 (4.2%) stddev-sans-first:2.24 10:07:07 INFO - PROCESS | 23331 | Values: 50.0 50.0 57.0 50.0 51.0 50.0 51.0 51.0 50.0 52.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 10:07:07 INFO - PROCESS | 23331 | Values: 60.0 59.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:79.50 stddev:3.48 (4.4%) stddev-sans-first:3.50 10:07:07 INFO - PROCESS | 23331 | Values: 84.0 79.0 88.0 79.0 78.0 78.0 80.0 79.0 79.0 85.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:130.00 stddev:13.09 (10.1%) stddev-sans-first:13.62 10:07:07 INFO - PROCESS | 23331 | Values: 125.0 124.0 129.0 131.0 119.0 125.0 152.0 122.0 158.0 137.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:166.50 stddev:3.33 (2.0%) stddev-sans-first:3.43 10:07:07 INFO - PROCESS | 23331 | Values: 167.0 169.0 162.0 167.0 166.0 164.0 167.0 160.0 159.0 166.0 10:07:07 INFO - PROCESS | 23331 | 10:07:07 INFO - PROCESS | 23331 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 10:07:07 INFO - PROCESS | 23331 | Values: 65.0 66.0 65.0 64.0 64.0 64.0 64.0 64.0 63.0 65.0 10:07:07 INFO - PROCESS | 23331 | -------- Summary: end -------- 10:07:07 INFO - PROCESS | 23331 | 10:07:08 INFO - TEST-INFO | 23331: exit 0 10:07:08 INFO - TEST-OK | kraken | took 61403ms 10:07:08 INFO - SUITE-END | took 1176s 10:07:09 INFO - Completed test suite (00:19:37) 10:07:09 INFO - javascript benchmark 10:07:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3045.923851903702, 3086.6141732283477, 3092.134202423322, 3127.3432579158375, 3141.481655526838, 5224.164133738603, 5286.0, 5296.046711591912, 5423.050966080935, 5484.177373393988, 5329.094054010818, 5344.679319240849, 5351.117065684167, 5351.919721204175, 5381.0852155133625, 4827.517248275162, 4918.950810491903, 4918.975405122969, 4930.285108659242, 5026.698398096126, 4980.900381992358, 5040.571551418134, 5041.949580504203, 5050.090983622947, 5052.696838189702, 4795.352627395292, 4821.035792841446, 4849.248366503198, 4858.222684370484, 5011.298418221452, 4902.068606964665, 4907.386576677915, 4973.602111831081, 4982.302477653131, 4987.576056035241, 4841.443234028096, 4856.660033797635, 4878.097551952865, 4884.218525036013, 4923.0, 5240.68555886648, 5287.233351164098, 5332.600054995852, 5349.7057661828585, 5407.756650950717, 5751.108578170349, 5756.222909907193, 5759.740811663485, 5759.8560035999435, 5765.596408251424, 5768.7692492300675, 5795.9420405796245, 5796.043652797283, 5798.565107616945, 5798.9420105799245, 5253.605979551549, 5304.389995150607, 5305.389880163753, 5362.115250983583, 5424.91862622057, 5779.653220806681, 5784.768609255667, 5798.797042103506, 5849.356570777211, 5859.941400586024, 5575.080111781592, 5578.637388569811, 5579.7489112990015, 5581.2186293918885, 5582.27430434041, 5604.495595396434, 5634.549236061105, 5645.491905728422, 5646.632968857011, 5658.745356458887, 5660.320761508646, 5703.372629010805, 5721.0, 5744.425557444222, 5806.1581070744505, 5747.540196784247, 5752.0, 5756.0502517084215, 5756.079027355687, 5756.7121643917635, 4348.326009468537, 4443.111377724468, 4447.2439685253585, 4491.393661855673, 4491.685582009228, 3920.313945059603, 3923.7645741255615, 3942.2312649033165, 3947.486826712509, 4028.657564107025, 5477.835664930059, 5478.671279723229, 5487.341519017744, 5488.53347465462, 5488.862778430492, 5845.240118784531, 5847.1229315603, 5854.063349864001, 5857.15071314657, 5925.259342582177, 5311.123664595376, 5357.4106848246665, 5375.973120134374, 5415.10650742631, 5442.102053161184, 4948.703077815343, 4950.6782059166035, 4951.133551628523, 4956.306117143603, 4998.325226094431, 5860.531157507475, 5897.7308376032315, 5904.586678932434, 5920.319163296103, 5977.820665380045, 5630.408807075362, 5631.070873306023, 5709.914351284651, 5746.597738158289, 5773.24947756789, 5933.317668468008, 5943.286805583358, 5950.256217972754, 5990.161377407254, 6011.789587364421, 5653.038983372754, 5666.829995100154, 5668.858278543069, 5669.461401166883, 5691.686957217393, 5540.141278101901, 5622.803201887914, 5700.087985922232, 5778.94221057784, 5786.942130578809, 5595.972020139873, 5667.0, 5703.8003669873015, 5766.884662306814, 5779.36426993022, 5691.914621280601, 5694.117411801178, 5695.145728140812, 5700.942990570207, 5701.4298570142655, 5375.758090885998, 5442.047641662773, 5569.10894256917, 5628.4090170533145, 5644.153376993484, 5499.725013749377, 5600.60795744294, 5620.775168993358, 5684.175794509855, 5730.5129064030025, 5538.584606154474, 5545.112781954868, 5550.666959982414, 5551.306086739158, 5554.583406244467], "value": 5302.13798670469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.4685342631357, 577.5552824325267, 595.8421018430117, 601.918740969969, 629.1475051305481, 42044.78977605112, 43597.564024359745, 43639.12721745567, 46894.76552617232, 46933.29600055993, 46014.53985460144, 46202.768986155024, 46428.30357544639, 46442.76778616111, 46474.0, 8895.911040889605, 9048.819023619524, 9050.094990500964, 9050.728478145633, 9054.547272636342, 8803.867941980876, 8900.109989001081, 8901.06538813425, 8901.73294801157, 8902.86645700315, 8914.50970196639, 8955.19403253707, 8960.327975401871, 8960.462372257685, 8961.865572016424, 8867.467951922874, 8902.243309318716, 8916.598753056129, 8917.509536975496, 8927.910720892773, 8847.29221662272, 8899.110088991114, 8918.375713700043, 8920.152585504431, 8924.687635932776, 41713.374299385534, 41848.0, 42145.157096858195, 42348.0, 42426.36360454596, 3200.471922132845, 3260.951085733716, 3428.434308339121, 3461.152017755641, 3515.2090779574646, 31366.0, 31628.36743265145, 32432.6756732432, 33271.33457330864, 33899.32201355959, 4124.6906482013665, 4163.208990291835, 4224.239636865363, 4281.850135245251, 4312.331588603772, 340299.0, 342197.0, 342648.0, 343974.0, 345368.9999999975, 34338.48492272618, 35805.0, 36066.278674426365, 59061.70469147627, 59780.402195977484, 37951.81024094862, 37957.62042379596, 37987.73202421569, 37994.43008354877, 38009.80995095007, 15311.234438278265, 15438.15090170029, 15454.613634659, 15460.458883939009, 15461.072335659672, 12908.35458227085, 13002.154859934075, 13006.479740810451, 13007.544735934196, 13008.414621342063, 8690.174433428814, 8693.565321734015, 8720.30237580992, 8768.632469233271, 8779.780505487413, 3375.7130643895543, 3388.6272510023878, 3413.1637748751714, 3415.692587667122, 3439.5872495300723, 960.8078384323023, 982.2436723722693, 1004.0260946881514, 1032.736652153711, 1071.8874518175635, 29735.0, 30632.84683576568, 30765.846170769004, 31045.68954310473, 31555.0, 1101.5869049106584, 1103.3876198709634, 1105.082781291526, 1114.7347760292128, 1129.88701129888, 15938.760918586231, 16328.265228064765, 16588.917055414644, 16696.666066678597, 16701.749473757904, 534193.0, 534430.0, 534764.0, 536587.0, 543444.0, 57884.71057644685, 58703.41296586979, 58711.70644146752, 58737.41262587405, 58792.41207587955, 1062.3731998121145, 1115.2026301194685, 1297.3189075735315, 1334.2394834944182, 1371.5885234429632, 28896.566551501324, 32840.835795820865, 33071.33857322792, 33177.50233746447, 33706.8314658435, 48763.0, 53234.0, 54479.18281225706, 54581.0, 59685.0, 496260.0, 500379.0, 500640.91668082913, 500767.0, 501561.0, 29869.402611948073, 30590.388192235587, 38406.0, 41343.7932810334, 43301.56698433102, 42735.7863210682, 46938.29592556046, 47192.29211561761, 47501.0, 47519.0, 39942.0, 40062.79968600138, 40070.39894401528, 40590.39114413227, 42182.0, 38903.44386224519, 39421.0, 39799.40300895431, 40940.795296024524, 41825.16349673051, 38817.61182388253, 40727.79636101801, 41814.79092604518, 42112.789436052626, 42312.36531451969, 34978.65021349856, 36342.27315453729, 37096.32266192111, 37123.81438092792, 38014.429783553824, 28200.43599127965, 28296.858515708114, 28298.434031318848, 28580.0, 28790.712092878803], "value": 19007.96710801485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.18360678635736, 397.3106659944995, 400.81762797926956, 401.68467752814036, 419.2704693832732, 12556.623301300964, 19077.332293369735, 19195.16096780644, 19888.0, 19991.40025799225, 18263.54341141469, 19760.30838920638, 19894.303699370525, 19947.10238039288, 19968.50078748032, 5783.334916484606, 6047.606905551147, 6049.122877182828, 6057.878842423149, 6062.18160548327, 5692.544596432275, 5837.182794408786, 5859.736311865976, 5865.970670146643, 5868.41315868414, 5774.0, 5826.475617194452, 5842.152887831262, 5852.063669812831, 5854.326752423462, 5594.328680558338, 5809.593328467009, 5841.7371218295275, 5866.560007999417, 5888.64668119912, 5195.974020129913, 5235.371755389339, 5241.68549887008, 5245.711485868275, 5246.763895624668, 23359.88320058389, 23539.529209415716, 23713.28860134199, 23777.405564860845, 24213.63679544808, 2371.359732872115, 2420.201333559921, 2536.606825941982, 2639.577667573179, 2784.9860750696116, 14629.268536573129, 14631.780523292158, 14662.853371466363, 14693.04495207808, 14843.777343339858, 1938.4378530226218, 1951.2097600471832, 1971.142552989454, 1974.9308774192978, 2018.798120187984, 81342.0, 82503.58748206281, 84126.99999999939, 84628.57685711594, 85973.57013214893, 10785.62250321235, 10897.400642964554, 11002.723001885239, 11088.667339979653, 11185.272957257752, 15570.221488925736, 15576.454824081104, 15580.143092129814, 15607.0, 15615.375384984472, 8203.384746144064, 8361.0, 8452.74641760748, 8529.14708529142, 8576.485410875368, 7900.841983160304, 8090.474119182234, 8132.634031468599, 8196.098429172785, 8250.092489826115, 4723.858284251478, 4775.2359622460235, 4794.568488836021, 4858.489858564871, 4944.085344211332, 1842.8065053169491, 2028.9797102029086, 2048.92828750993, 2056.105594066586, 2059.1866212845844, 879.3624622148891, 914.2685851319051, 975.4050029482011, 986.3194395866828, 1016.8865092723456, 17650.470485885446, 18727.90636046811, 19150.425487235407, 19678.70481942772, 19821.008949552466, 971.6307803034942, 978.3982850547005, 986.5856340337076, 990.1187942730975, 1024.0, 10189.898101019044, 10928.672139835817, 10983.286086404518, 11013.0, 11057.668269951915, 446272.0, 458792.0, 461068.0, 469026.0, 474605.0, 66210.0, 82056.0, 83626.58186709149, 84778.5761071203, 87439.56280218686, 975.8926518083006, 988.165000574499, 1002.6540843409158, 1008.8486726990864, 1016.2175125153738, 15803.36786528526, 16205.0, 16350.509484715478, 17691.823081769016, 17704.91147544254, 39339.60660393359, 39774.801125994185, 40337.0, 40496.3925541123, 40925.86112083092, 53301.0, 53955.460445395045, 55190.0, 55200.17199741927, 56485.0, 7732.342750866239, 8630.482171069505, 8632.179942905414, 8670.653173872974, 8916.19754222123, 12193.512259509875, 13261.66845828862, 13435.932820335836, 13674.31628418595, 13720.588382348546, 11592.1305902056, 11885.405729713308, 11924.403779811148, 12052.758944820878, 12102.75794484123, 11228.719282018015, 11445.882511735355, 11492.36791976416, 11562.595309164139, 11890.405479726152, 11808.527658893549, 11993.700157496132, 12026.939865300967, 12314.384280785745, 12491.063170262085, 7986.84026319482, 8081.272685458337, 8219.260266576037, 8272.958635207026, 8372.372072094495, 9759.868339026892, 11407.665087266785, 11704.239224450383, 11919.165658403828, 11983.34091624969], "value": 9279.34560507256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1131.4851742457183, 1447.421031587365, 1486.5911874234587, 1632.6816270827194, 1777.271318759309, 8047.436679432442, 8987.146221108993, 9226.215771659401, 9302.488363140033, 9639.325247232697, 9224.8616270756, 9310.30172737044, 9337.486438245895, 9357.111074447934, 9402.764930876732, 2401.6037353836596, 2423.5516429460517, 2425.745296743843, 2431.513697260546, 2433.2480613957923, 2356.3166681662296, 2388.8088952883813, 2395.125779090628, 2406.2300063979533, 2411.602085655865, 2355.693759811231, 2364.432536191319, 2368.384220102777, 2436.2082323244927, 2437.183543512925, 2172.532905425325, 2196.3410976707005, 2199.2192771566106, 2201.9779802202015, 2231.4532939429782, 2334.8832558372183, 2355.7173139223232, 2357.728861180969, 2359.2096647623066, 2360.173939121311, 9052.230560402373, 9193.678221262291, 9249.028851970581, 9390.577424015935, 9511.619535218582, 5295.602829787743, 5493.533049690741, 5521.2270282160525, 5523.033469142884, 5531.170324451325, 3372.5615669962995, 3419.863205471778, 3458.602260740022, 3462.8614855405754, 3525.1715846775987, 1079.1097344690584, 1086.0, 1088.983665245022, 1113.3765091548678, 1115.1970581181536, 13097.803532947013, 13126.14680045794, 13241.337933103307, 13264.801027984684, 13315.40080696371, 4607.631389488833, 4674.392328997209, 4883.804647814119, 4913.852584422473, 4920.4833492483485, 10168.135708464715, 10197.337173083726, 10200.642977495752, 10219.335743176669, 10223.897761022294, 5544.251526044013, 5560.304961879765, 5561.249231353796, 5571.721413929287, 5572.721363931787, 4370.672199585044, 4507.301368287921, 4954.256861470808, 5036.395632524121, 5182.844514664566, 3805.5813860475328, 3887.6501114899793, 3934.567197608261, 3953.5651078381356, 4010.498687664042, 1189.5776999165346, 1201.6575276046285, 1202.4048096192441, 1217.9573714919936, 1232.2298563397876, 869.8651708985118, 911.48956584313, 984.2814745236018, 1007.3502590828874, 1008.5562352564848, 5967.075103358986, 6033.3664965178905, 6042.184305118841, 6262.499000079983, 6362.0, 490.1911845455028, 492.87924458507894, 494.8911239527298, 496.3051727581343, 498.39445074234976, 4180.268453020709, 4189.853355132555, 4218.78906054696, 4237.8304867805555, 4241.830326786894, 11183.105351571854, 11753.941230293794, 11769.352685602204, 11771.411429428494, 11774.116941229442, 10917.727056823644, 11445.427728613537, 11454.656360309204, 11637.592684256011, 11667.299962002307, 8316.916830831615, 8502.44734092282, 8503.1921967413, 8503.319734421231, 8509.361797865186, 6261.499080073492, 6263.874722505616, 6281.654509002049, 6378.170837791057, 6504.804855854332, 10701.678949631523, 10737.785244294913, 10788.083012943987, 10864.565417383532, 10904.073153782017, 10435.0, 10923.399213043358, 10931.945340273247, 11134.777304454028, 11201.831972520582, 6955.408790252885, 7192.2088570258275, 7193.460490463341, 8217.630206640835, 8397.580120994047, 8019.799505012421, 8390.958045209734, 8776.122387761172, 8899.243564297107, 9175.954120229357, 7829.256220659028, 8004.51972881629, 8035.718749843727, 8225.383096267684, 8342.457740246864, 7947.245011723877, 8025.438219324825, 8068.798280043045, 8144.226298501633, 8171.7548473545885, 7369.668364923484, 7401.8149546261775, 7452.627368631438, 7456.440766942393, 7568.243175682608, 5405.4594540547205, 5409.188621706776, 5424.077906755782, 5525.060739674184, 5635.464630860061, 5625.46558076982, 5633.267675202197, 5653.349864765604, 5701.714914254354, 5776.393478684678], "value": 4690.970995119018, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.9617094034186, 333.4631243697646, 342.1497578517384, 347.60893994256463, 348.644382729616, 5288.418273989868, 5290.470952904708, 5300.9204861927055, 5302.3106996090555, 5308.097623404021, 5138.203578445337, 5165.612579056577, 5222.216667499867, 5231.816886408977, 5243.187305967581, 3900.8687480630583, 3948.2498325318243, 4113.9177216455655, 4114.526829414625, 4215.072684009513, 4162.334026555752, 4165.208610364036, 4167.041580436501, 4174.144300418422, 4201.537830838605, 4130.586941305861, 4132.29750942339, 4134.751914885116, 4137.862087925814, 4144.21259960608, 4013.3177359848896, 4104.589541045902, 4132.462779838632, 4133.979330103345, 4141.958580414187, 3678.988278223483, 3681.44778283257, 3684.8341824617955, 3687.299413111527, 3688.9631103689157, 5073.0107629012355, 5092.745362731848, 5092.911906118511, 5094.923576146361, 5123.667846359973, 4617.953820461819, 4655.701971663516, 4671.369365135697, 4723.433188017426, 4733.266343716695, 5399.514043736082, 5401.756920938567, 5408.161734931053, 5409.43200963897, 5409.83770486886, 4041.9797901010306, 4046.372812214082, 4063.837446502166, 4074.4092106644666, 4076.470058892325, 5346.572274218053, 5643.802466913638, 5648.4633959773755, 5675.148727690838, 5709.685967271798, 4000.3398589584995, 4011.679065674739, 4037.8384864605086, 4047.1905618876344, 4076.001379662002, 4091.0, 4095.52901416335, 4119.0, 4123.319652257344, 4131.752094874317, 4595.494495605481, 4717.080169367009, 4717.858464246078, 4742.099001189763, 4754.857354279377, 5498.130635583921, 5500.779968801283, 5505.862353441116, 5548.225887056472, 5550.333959924754, 1399.9720005599831, 1418.5106138382241, 1422.7154569086224, 1428.6642638979831, 1433.6702558411416, 886.866969954512, 908.5411867007119, 922.9432300016443, 934.2619330728784, 944.918068811221, 5163.509466600667, 5164.457731938168, 5312.123499622519, 5355.946440535623, 5365.543928766098, 5547.334319881559, 5555.194496797942, 5558.583106267046, 5593.916091258634, 5619.971900140555, 5179.3426103647225, 5243.726678644109, 5283.920741188886, 5314.681119132864, 5318.361796584435, 2959.4820906341306, 2977.1663934098488, 2981.8509074546187, 2985.1044686593937, 2986.25343664084, 5733.140028995684, 5786.681732504752, 5787.363390027093, 5789.739461724233, 5791.623544469596, 5546.223528705985, 5686.914696279559, 5724.227229323988, 5738.594044459134, 5745.569082318842, 5738.913916291259, 5748.338941021752, 5752.856178595569, 5786.537077033827, 5801.36185019639, 5347.411784703757, 5562.5549956002615, 5595.244641973281, 5629.211910332475, 5647.983362994701, 5499.532539734168, 5543.307086614173, 5546.7781288748, 5552.306546503355, 5560.332760068818, 5732.140178973187, 5753.568482363756, 5769.451902069297, 5807.738651760766, 5818.970905145447, 3855.7300988930506, 4040.0909795295825, 4043.636072753349, 4052.63526282636, 4093.2222877653153, 5616.652003519208, 5618.2134501170685, 5618.2415373924, 5639.7744090237575, 5653.943460565341, 5505.788726480168, 5520.420355862577, 5527.861803454785, 5534.1975413564005, 5535.7785688572, 5262.973720124692, 5411.540019098421, 5444.564434845282, 5459.5632349412745, 5465.426130256425, 5371.838844834661, 5378.515933565998, 5389.461053894579, 5395.487428694268, 5409.5672346213, 5265.499332690165, 5280.1815718563685, 5373.543248823894, 5442.14966911252, 5465.754041068241, 5327.280817089643, 5350.250964864844, 5402.7303583658595, 5413.078357183143, 5418.349798024262], "value": 4198.2839369050325, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.8155461134713, 746.0338861174778, 767.9308862202402, 795.9522428654282, 805.9460911614574, 14746.0, 16398.7540186897, 16455.341786328547, 16557.420490282842, 16700.832991670093, 15300.617484562892, 15420.228988550556, 15433.922830385833, 15447.5365739028, 15449.22753862308, 430.03028171473284, 431.06243919475173, 795.4193438789702, 811.5130921447144, 821.0311832038193, 759.741687826139, 802.2458888644685, 802.9759107226793, 816.808050108224, 817.7996390884242, 865.2040123086775, 867.9349048821332, 868.0494858130337, 869.0570730757136, 871.5903525343078, 445.6969260902585, 862.8015556422058, 863.2834747159874, 863.6977058029671, 863.745195167424, 332.8369099141427, 588.9941100588982, 622.919020527333, 626.890294198518, 628.5788521690479, 15291.464798731991, 15296.694066118727, 15312.387504499806, 15316.0, 15320.616984575363, 13298.202107873558, 13464.124831885994, 13660.795088073686, 13940.571235524101, 14013.299335033207, 12126.27242365452, 12146.635600931897, 12177.817332740016, 12236.0, 12274.570390036395, 4402.933955990663, 4428.601425871655, 4457.0, 4483.170613436525, 4484.551544845522, 82973.0, 83060.16939830585, 83677.58161209215, 83910.0, 83923.16076839276, 10804.405757683402, 10897.618583349704, 11020.283681560673, 11439.828402573969, 11485.0, 20785.064672089793, 20919.58160836805, 21108.894455527625, 21315.25396611111, 21382.14471421157, 7779.299863012356, 7873.763787086396, 7961.1242763295995, 7963.721269755647, 8020.71927482547, 4457.086297309044, 4479.7984090716, 4550.112728018004, 4681.274402467623, 4698.36572062774, 3131.123285480069, 3206.150370151912, 3211.2132527530903, 3222.8710851565675, 3227.1609381560493, 578.6238944685904, 598.7964092208583, 600.4445887554083, 608.3946473259135, 625.3683779382765, 651.4137276451232, 655.6852710698892, 659.947204223661, 668.1347654787062, 679.0222080204489, 8819.250363719028, 8974.058894128972, 9036.86798977516, 9144.588493517807, 9401.341906066646, 591.2786400591272, 608.6378604730179, 629.2386212682613, 640.2349192714661, 647.899575565788, 4160.667146628323, 4377.430933978626, 4390.407295015132, 4466.196084704719, 4477.753723545171, 95374.52312738531, 113610.43194783974, 128312.0, 134444.32777836244, 135772.0, 93279.0, 107617.46191268993, 109530.0, 112704.43647781709, 113891.0, 56875.43124568784, 59624.0, 92449.53775231216, 94581.0, 95308.52345738227, 7846.725364612211, 8144.1448647893485, 8184.304334131665, 8289.585520723818, 8319.83360332778, 55232.72383638056, 55842.0, 56049.71975140261, 56831.43168568261, 57482.42517574771, 79313.20686793058, 83145.58427207825, 86208.56895715481, 88100.0, 89660.0, 10712.73218169552, 10915.290506117077, 10974.067204287405, 11209.607663731731, 11588.072954163477, 11364.54541818318, 11657.708557286136, 11750.23623464472, 11765.705857353634, 11956.70108247266, 10143.89856101459, 10754.24720269605, 10840.0, 10976.451177440937, 11425.60010399632, 12629.810552841898, 12720.300383478627, 13573.59279221625, 13717.245551494763, 13933.303334833421, 11572.59495917639, 12106.394680266128, 12395.504179832706, 12421.068419868727, 12462.626121216377, 10666.253362264568, 10741.89258107409, 10847.294925830112, 10924.399158046066, 12059.81910271329, 10264.281500295207, 11016.834747478633, 11299.209055366375, 11495.367754773568, 11572.18994670365], "value": 7287.407047309868, "unit": "score"}], "name": "dromaeo_css", "value": 7155.540431992033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 110.0, 111.0, 111.0, 111.0, 111.0, 110.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 118.0, 171.0, 118.0, 124.0, 118.0, 120.0, 176.0, 117.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 162.0, 160.0, 161.0, 161.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 102.0, 76.0, 76.0, 76.0, 79.0, 110.0, 76.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 116.0, 82.0, 82.0, 81.0, 102.0, 81.0, 82.0, 112.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 57.0, 50.0, 51.0, 50.0, 51.0, 51.0, 50.0, 52.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 88.0, 79.0, 78.0, 78.0, 80.0, 79.0, 79.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 124.0, 129.0, 131.0, 119.0, 125.0, 152.0, 122.0, 158.0, 137.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 162.0, 167.0, 166.0, 164.0, 167.0, 160.0, 159.0, 166.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 65.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1489.4000000000003, "alertThreshold": 2.0}]} 10:07:09 INFO - Return code: 0 10:07:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:09 INFO - # TBPL SUCCESS # 10:07:09 INFO - Running post-action listener: _resource_record_post_action 10:07:09 INFO - [mozharness: 2016-09-26 17:07:09.246632Z] Finished run-tests step (success) 10:07:09 INFO - Running post-run listener: _resource_record_post_run 10:07:09 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 399339520; Read time: 0; Write time: 388440 10:07:09 INFO - TinderboxPrint: CPU usage
13.0% 10:07:09 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 10:07:09 INFO - TinderboxPrint: I/O write bytes / time
399,339,520 / 388,440 10:07:09 INFO - TinderboxPrint: CPU idle
8,287.5 (87.0%) 10:07:09 INFO - TinderboxPrint: CPU user
1,189.5 (12.5%) 10:07:09 INFO - TinderboxPrint: Swap in / out
0 / 0 10:07:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110776320; Read time: 0; Write time: 116816 10:07:09 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288563200; Read time: 0; Write time: 271624 10:07:09 INFO - Running post-run listener: _upload_blobber_files 10:07:09 INFO - Blob upload gear active. 10:07:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:07:09 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'] 10:07:09 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 10:07:10 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:07:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:11 INFO - (blobuploader) - INFO - Done attempting. 10:07:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:07:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:13 INFO - (blobuploader) - INFO - Done attempting. 10:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:17 INFO - (blobuploader) - INFO - Done attempting. 10:07:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:17 INFO - Return code: 0 10:07:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0632edf5fd4b83fac6425f419a56595c3dac35a89e81dd7689e08034666b195e7fadb4b69d2a7b6acbb698d9e9d5c41e1f2a176401b9b39c0032b60995baa846", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37dc3942ae675bc1a40e1bdafd72fc66162d403bddaf649984946690470d6c67ee9f60afea75c2296b272909c272bd122f08efff576e6c0bb229c6a0620a0cf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70852fe9a1d08c553ec14b7beb496310558453ede825e90a602a1e49449a3eb2fe7168015044ff1d22f13ab27372b5c8160e367b4224f6463b8fc3cd3844a9f5"} 10:07:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:17 INFO - Contents: 10:07:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0632edf5fd4b83fac6425f419a56595c3dac35a89e81dd7689e08034666b195e7fadb4b69d2a7b6acbb698d9e9d5c41e1f2a176401b9b39c0032b60995baa846", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37dc3942ae675bc1a40e1bdafd72fc66162d403bddaf649984946690470d6c67ee9f60afea75c2296b272909c272bd122f08efff576e6c0bb229c6a0620a0cf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70852fe9a1d08c553ec14b7beb496310558453ede825e90a602a1e49449a3eb2fe7168015044ff1d22f13ab27372b5c8160e367b4224f6463b8fc3cd3844a9f5"} 10:07:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:17 INFO - Copying logs to upload dir... 10:07:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.831513 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-26 10:07:17.760568) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 10:07:17.764609) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 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/0632edf5fd4b83fac6425f419a56595c3dac35a89e81dd7689e08034666b195e7fadb4b69d2a7b6acbb698d9e9d5c41e1f2a176401b9b39c0032b60995baa846", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37dc3942ae675bc1a40e1bdafd72fc66162d403bddaf649984946690470d6c67ee9f60afea75c2296b272909c272bd122f08efff576e6c0bb229c6a0620a0cf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70852fe9a1d08c553ec14b7beb496310558453ede825e90a602a1e49449a3eb2fe7168015044ff1d22f13ab27372b5c8160e367b4224f6463b8fc3cd3844a9f5"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474896600/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/0632edf5fd4b83fac6425f419a56595c3dac35a89e81dd7689e08034666b195e7fadb4b69d2a7b6acbb698d9e9d5c41e1f2a176401b9b39c0032b60995baa846", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/37dc3942ae675bc1a40e1bdafd72fc66162d403bddaf649984946690470d6c67ee9f60afea75c2296b272909c272bd122f08efff576e6c0bb229c6a0620a0cf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/70852fe9a1d08c553ec14b7beb496310558453ede825e90a602a1e49449a3eb2fe7168015044ff1d22f13ab27372b5c8160e367b4224f6463b8fc3cd3844a9f5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 10:07:17.790324) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 10:07:17.790671) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1474908399.158107-711904998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 10:07:17.873123) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 10:07:17.873472) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-26 10:07:17.873809) ========= ========= Total master_lag: 0.18 =========