builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1456419184.85 results: success (0) buildid: 20160225062926 builduid: 56c392f52ba64d27891425204e74f891 revision: 13f8d3d302ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.847093) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.847507) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.847820) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006680 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.897103) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.897395) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.993849) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:04.994135) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004654 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.056637) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.056938) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.057287) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.057574) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 08:53:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.3M=0s 2016-02-25 08:53:05 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163397 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.236077) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.236376) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014538 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.285410) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.287850) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13f8d3d302ce --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13f8d3d302ce --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 08:53:05,748 Setting DEBUG logging. 2016-02-25 08:53:05,748 attempt 1/10 2016-02-25 08:53:05,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/13f8d3d302ce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 08:53:06,021 unpacking tar archive at: mozilla-beta-13f8d3d302ce/testing/mozharness/ program finished with exit code 0 elapsedTime=0.588778 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.888978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.889330) ========= echo 13f8d3d302ce in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'13f8d3d302ce'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13f8d3d302ce program finished with exit code 0 elapsedTime=0.004741 script_repo_revision: '13f8d3d302ce' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.944916) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.945201) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:53:05.957975) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-25 08:53:05.958256) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:53:06 INFO - MultiFileLogger online at 20160225 08:53:06 in /builds/slave/test-pgo 08:53:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:53:06 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:53:06 INFO - {'append_to_log': False, 08:53:06 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:53:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:53:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:53:06 INFO - 'branch': 'Mozilla-Beta', 08:53:06 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:06 INFO - 'config_files': ('talos/linux_config.py',), 08:53:06 INFO - 'default_actions': ('clobber', 08:53:06 INFO - 'read-buildbot-config', 08:53:06 INFO - 'download-and-extract', 08:53:06 INFO - 'populate-webroot', 08:53:06 INFO - 'create-virtualenv', 08:53:06 INFO - 'install', 08:53:06 INFO - 'run-tests'), 08:53:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:06 INFO - 'download_minidump_stackwalk': True, 08:53:06 INFO - 'download_symbols': 'ondemand', 08:53:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:53:06 INFO - 'tooltool.py': '/tools/tooltool.py', 08:53:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:53:06 INFO - '/tools/misc-python/virtualenv.py')}, 08:53:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:06 INFO - 'installer_path': 'installer.exe', 08:53:06 INFO - 'log_level': 'info', 08:53:06 INFO - 'log_name': 'talos', 08:53:06 INFO - 'log_to_console': True, 08:53:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:53:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:53:06 INFO - 'opt_config_files': (), 08:53:06 INFO - 'pip_index': False, 08:53:06 INFO - 'sps_profile': False, 08:53:06 INFO - 'sps_profile_interval': 0, 08:53:06 INFO - 'suite': 'dromaeojs', 08:53:06 INFO - 'system_bits': '32', 08:53:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:06 INFO - 'title': 'talos-linux64-ix-015', 08:53:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:06 INFO - 'use_talos_json': True, 08:53:06 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:53:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:06 INFO - 'work_dir': 'build'} 08:53:06 INFO - ##### 08:53:06 INFO - ##### Running clobber step. 08:53:06 INFO - ##### 08:53:06 INFO - Running pre-action listener: _resource_record_pre_action 08:53:06 INFO - Running main action method: clobber 08:53:06 INFO - rmtree: /builds/slave/test-pgo/build 08:53:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:53:06 INFO - Running post-action listener: _resource_record_post_action 08:53:06 INFO - ##### 08:53:06 INFO - ##### Running read-buildbot-config step. 08:53:06 INFO - ##### 08:53:06 INFO - Running pre-action listener: _resource_record_pre_action 08:53:06 INFO - Running main action method: read_buildbot_config 08:53:06 INFO - Using buildbot properties: 08:53:06 INFO - { 08:53:06 INFO - "properties": { 08:53:06 INFO - "buildnumber": 121, 08:53:06 INFO - "product": "firefox", 08:53:06 INFO - "script_repo_revision": "production", 08:53:06 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:53:06 INFO - "repository": "", 08:53:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:53:06 INFO - "buildid": "20160225062926", 08:53:06 INFO - "slavename": "talos-linux64-ix-015", 08:53:06 INFO - "pgo_build": "True", 08:53:06 INFO - "basedir": "/builds/slave/test-pgo", 08:53:06 INFO - "project": "", 08:53:06 INFO - "platform": "ubuntu64_hw", 08:53:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:53:06 INFO - "slavebuilddir": "test", 08:53:06 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:53:06 INFO - "branch": "mozilla-beta", 08:53:06 INFO - "repo_path": "releases/mozilla-beta", 08:53:06 INFO - "stage_platform": "linux64-pgo", 08:53:06 INFO - "builduid": "56c392f52ba64d27891425204e74f891", 08:53:06 INFO - "revision": "13f8d3d302ce" 08:53:06 INFO - }, 08:53:06 INFO - "sourcestamp": { 08:53:06 INFO - "repository": "", 08:53:06 INFO - "hasPatch": false, 08:53:06 INFO - "project": "", 08:53:06 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:53:06 INFO - "changes": [ 08:53:06 INFO - { 08:53:06 INFO - "category": null, 08:53:06 INFO - "files": [ 08:53:06 INFO - { 08:53:06 INFO - "url": null, 08:53:06 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:53:06 INFO - } 08:53:06 INFO - ], 08:53:06 INFO - "repository": "", 08:53:06 INFO - "rev": "13f8d3d302ce", 08:53:06 INFO - "who": "sendchange", 08:53:06 INFO - "when": 1456419155, 08:53:06 INFO - "number": 7653694, 08:53:06 INFO - "comments": "Bug 1246956 - Add originURI to Sessionstore. r=bz r=Yoric r=mfinkle, a=sylvestre", 08:53:06 INFO - "project": "", 08:53:06 INFO - "at": "Thu 25 Feb 2016 08:52:35", 08:53:06 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:53:06 INFO - "revlink": "", 08:53:06 INFO - "properties": [ 08:53:06 INFO - [ 08:53:06 INFO - "buildid", 08:53:06 INFO - "20160225062926", 08:53:06 INFO - "Change" 08:53:06 INFO - ], 08:53:06 INFO - [ 08:53:06 INFO - "builduid", 08:53:06 INFO - "56c392f52ba64d27891425204e74f891", 08:53:06 INFO - "Change" 08:53:06 INFO - ], 08:53:06 INFO - [ 08:53:06 INFO - "pgo_build", 08:53:06 INFO - "True", 08:53:06 INFO - "Change" 08:53:06 INFO - ] 08:53:06 INFO - ], 08:53:06 INFO - "revision": "13f8d3d302ce" 08:53:06 INFO - } 08:53:06 INFO - ], 08:53:06 INFO - "revision": "13f8d3d302ce" 08:53:06 INFO - } 08:53:06 INFO - } 08:53:06 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:53:06 INFO - Running post-action listener: _resource_record_post_action 08:53:06 INFO - ##### 08:53:06 INFO - ##### Running download-and-extract step. 08:53:06 INFO - ##### 08:53:06 INFO - Running pre-action listener: _resource_record_pre_action 08:53:06 INFO - Running main action method: download_and_extract 08:53:06 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/test_packages.json 08:53:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:53:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:53:06 INFO - Downloaded 1270 bytes. 08:53:06 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:53:06 INFO - Using the following test package requirements: 08:53:06 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:53:06 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:53:06 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'jsshell-linux-x86_64.zip'], 08:53:06 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:53:06 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:53:06 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:53:06 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:53:06 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:53:06 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:53:06 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:53:06 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:53:06 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:53:08 INFO - Downloaded 21584086 bytes. 08:53:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:53:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:08 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:53:09 INFO - Return code: 0 08:53:09 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:09 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:09 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:53:10 INFO - Downloaded 21584086 bytes. 08:53:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:53:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:53:10 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:53:11 INFO - Return code: 0 08:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:53:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:53:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:53:14 INFO - Downloaded 11132038 bytes. 08:53:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 08:53:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:53:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 08:53:14 INFO - Return code: 0 08:53:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:53:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:53:16 INFO - Downloaded 57941618 bytes. 08:53:16 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:53:16 INFO - mkdir: /builds/slave/test-pgo/properties 08:53:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:53:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:53:16 INFO - Contents: 08:53:16 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:53:17 INFO - Running post-action listener: _resource_record_post_action 08:53:17 INFO - Running post-action listener: set_extra_try_arguments 08:53:17 INFO - ##### 08:53:17 INFO - ##### Running populate-webroot step. 08:53:17 INFO - ##### 08:53:17 INFO - Running pre-action listener: _resource_record_pre_action 08:53:17 INFO - Running main action method: populate_webroot 08:53:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:17 INFO - '--tppagecycles', 08:53:17 INFO - '7'], 08:53:17 INFO - 'tests': ['tsvgm']}}, 08:53:17 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:53:17 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:17 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:53:17 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:53:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'tests': ['damp', 'tps']}, 08:53:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['damp', 'tps']}, 08:53:17 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:53:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:17 INFO - 'other': {'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'other_l64': {'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'other_nol64': {'tests': ['a11yr', 08:53:17 INFO - 'ts_paint', 08:53:17 INFO - 'tpaint', 08:53:17 INFO - 'sessionrestore', 08:53:17 INFO - 'sessionrestore_no_auto_restore']}, 08:53:17 INFO - 'svgr': {'tests': ['tsvgx', 08:53:17 INFO - 'tsvgr_opacity', 08:53:17 INFO - 'tart', 08:53:17 INFO - 'tscrollx', 08:53:17 INFO - 'cart']}, 08:53:17 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['tsvgx', 08:53:17 INFO - 'tsvgr_opacity', 08:53:17 INFO - 'tart', 08:53:17 INFO - 'tscrollx', 08:53:17 INFO - 'cart']}, 08:53:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'tests': ['tp5o']}, 08:53:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'talos_options': ['--e10s'], 08:53:17 INFO - 'tests': ['tp5o']}, 08:53:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'talos_options': ['--xperf_path', 08:53:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:17 INFO - 'tests': ['tp5n']}, 08:53:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:17 INFO - 'talos_options': ['--e10s', 08:53:17 INFO - '--xperf_path', 08:53:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:17 INFO - 'tests': ['tp5n']}}, 08:53:17 INFO - 'talos.zip': {'path': '', 08:53:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:17 INFO - Running post-action listener: _resource_record_post_action 08:53:17 INFO - ##### 08:53:17 INFO - ##### Running create-virtualenv step. 08:53:17 INFO - ##### 08:53:17 INFO - Running pre-action listener: _resource_record_pre_action 08:53:17 INFO - Running main action method: create_virtualenv 08:53:17 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:53:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 08:53:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:53:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:17 INFO - Using real prefix '/usr' 08:53:17 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:53:17 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:19 INFO - Installing pip.................done. 08:53:19 INFO - Return code: 0 08:53:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:53:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:19 INFO - Running command: ['/builds/slave/test-pgo/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-pgo/build 08:53:19 INFO - Copy/paste: /builds/slave/test-pgo/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 08:53:19 INFO - Using env: {'DISPLAY': ':0', 08:53:19 INFO - 'HOME': '/home/cltbld', 08:53:19 INFO - 'LANG': 'en_US.UTF-8', 08:53:19 INFO - 'LANGUAGE': 'en_US:en', 08:53:19 INFO - 'LOGNAME': 'cltbld', 08:53:19 INFO - 'MAIL': '/var/mail/cltbld', 08:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:19 INFO - 'MOZ_NO_REMOTE': '1', 08:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:19 INFO - 'NO_EM_RESTART': '1', 08:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:19 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:19 INFO - 'SHELL': '/bin/bash', 08:53:19 INFO - 'SHLVL': '1', 08:53:19 INFO - 'TERM': 'linux', 08:53:19 INFO - 'TMOUT': '86400', 08:53:19 INFO - 'USER': 'cltbld', 08:53:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:19 INFO - '_': '/tools/buildbot/bin/python'} 08:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:19 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 08:53:19 INFO - Cleaning up... 08:53:19 INFO - Return code: 0 08:53:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:53:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:19 INFO - Running command: ['/builds/slave/test-pgo/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>=0.7.1'] in /builds/slave/test-pgo/build 08:53:19 INFO - Copy/paste: /builds/slave/test-pgo/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>=0.7.1 08:53:19 INFO - Using env: {'DISPLAY': ':0', 08:53:19 INFO - 'HOME': '/home/cltbld', 08:53:19 INFO - 'LANG': 'en_US.UTF-8', 08:53:19 INFO - 'LANGUAGE': 'en_US:en', 08:53:19 INFO - 'LOGNAME': 'cltbld', 08:53:19 INFO - 'MAIL': '/var/mail/cltbld', 08:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:19 INFO - 'MOZ_NO_REMOTE': '1', 08:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:19 INFO - 'NO_EM_RESTART': '1', 08:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:19 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:19 INFO - 'SHELL': '/bin/bash', 08:53:19 INFO - 'SHLVL': '1', 08:53:19 INFO - 'TERM': 'linux', 08:53:19 INFO - 'TMOUT': '86400', 08:53:19 INFO - 'USER': 'cltbld', 08:53:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:19 INFO - '_': '/tools/buildbot/bin/python'} 08:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:20 INFO - Downloading/unpacking psutil>=0.7.1 08:53:20 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 08:53:20 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 08:53:20 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 08:53:20 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 08:53:20 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 08:53:20 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 08:53:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:22 INFO - Installing collected packages: psutil 08:53:22 INFO - Running setup.py install for psutil 08:53:22 INFO - building 'psutil._psutil_linux' extension 08:53:22 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 08:53:22 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 08:53:22 INFO - building 'psutil._psutil_posix' extension 08:53:22 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 08:53:22 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 08:53:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:22 INFO - Successfully installed psutil 08:53:22 INFO - Cleaning up... 08:53:22 INFO - Return code: 0 08:53:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:53:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:22 INFO - Running command: ['/builds/slave/test-pgo/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.0.0'] in /builds/slave/test-pgo/build 08:53:22 INFO - Copy/paste: /builds/slave/test-pgo/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.0.0 08:53:22 INFO - Using env: {'DISPLAY': ':0', 08:53:22 INFO - 'HOME': '/home/cltbld', 08:53:22 INFO - 'LANG': 'en_US.UTF-8', 08:53:22 INFO - 'LANGUAGE': 'en_US:en', 08:53:22 INFO - 'LOGNAME': 'cltbld', 08:53:22 INFO - 'MAIL': '/var/mail/cltbld', 08:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:22 INFO - 'MOZ_NO_REMOTE': '1', 08:53:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:22 INFO - 'NO_EM_RESTART': '1', 08:53:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:22 INFO - 'SHELL': '/bin/bash', 08:53:22 INFO - 'SHLVL': '1', 08:53:22 INFO - 'TERM': 'linux', 08:53:22 INFO - 'TMOUT': '86400', 08:53:22 INFO - 'USER': 'cltbld', 08:53:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:22 INFO - '_': '/tools/buildbot/bin/python'} 08:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:53:22 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 08:53:22 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 08:53:22 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 08:53:22 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 08:53:22 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 08:53:22 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 08:53:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:53:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:53:24 INFO - Installing collected packages: mozsystemmonitor 08:53:24 INFO - Running setup.py install for mozsystemmonitor 08:53:24 INFO - Successfully installed mozsystemmonitor 08:53:24 INFO - Cleaning up... 08:53:24 INFO - Return code: 0 08:53:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:24 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:24 INFO - Running command: ['/builds/slave/test-pgo/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-pgo/build 08:53:24 INFO - Copy/paste: /builds/slave/test-pgo/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 08:53:24 INFO - Using env: {'DISPLAY': ':0', 08:53:24 INFO - 'HOME': '/home/cltbld', 08:53:24 INFO - 'LANG': 'en_US.UTF-8', 08:53:24 INFO - 'LANGUAGE': 'en_US:en', 08:53:24 INFO - 'LOGNAME': 'cltbld', 08:53:24 INFO - 'MAIL': '/var/mail/cltbld', 08:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:24 INFO - 'MOZ_NO_REMOTE': '1', 08:53:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:24 INFO - 'NO_EM_RESTART': '1', 08:53:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:24 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:24 INFO - 'SHELL': '/bin/bash', 08:53:24 INFO - 'SHLVL': '1', 08:53:24 INFO - 'TERM': 'linux', 08:53:24 INFO - 'TMOUT': '86400', 08:53:24 INFO - 'USER': 'cltbld', 08:53:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:24 INFO - '_': '/tools/buildbot/bin/python'} 08:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:26 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 08:53:26 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 08:53:26 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 08:53:26 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 08:53:26 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 08:53:26 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 08:53:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:53:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:27 INFO - Downloading docopt-0.6.1.tar.gz 08:53:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:27 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:27 INFO - Running setup.py install for blobuploader 08:53:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:27 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:53:27 INFO - Running setup.py install for requests 08:53:28 INFO - Running setup.py install for docopt 08:53:28 INFO - Successfully installed blobuploader requests docopt 08:53:28 INFO - Cleaning up... 08:53:28 INFO - Return code: 0 08:53:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 08:53:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/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 08:53:28 INFO - Using env: {'DISPLAY': ':0', 08:53:28 INFO - 'HOME': '/home/cltbld', 08:53:28 INFO - 'LANG': 'en_US.UTF-8', 08:53:28 INFO - 'LANGUAGE': 'en_US:en', 08:53:28 INFO - 'LOGNAME': 'cltbld', 08:53:28 INFO - 'MAIL': '/var/mail/cltbld', 08:53:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:28 INFO - 'MOZ_NO_REMOTE': '1', 08:53:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:28 INFO - 'NO_EM_RESTART': '1', 08:53:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:28 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:28 INFO - 'SHELL': '/bin/bash', 08:53:28 INFO - 'SHLVL': '1', 08:53:28 INFO - 'TERM': 'linux', 08:53:28 INFO - 'TMOUT': '86400', 08:53:28 INFO - 'USER': 'cltbld', 08:53:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:28 INFO - '_': '/tools/buildbot/bin/python'} 08:53:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:53:28 INFO - Running setup.py (path:/tmp/pip-zEx6LD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:53:28 INFO - Running setup.py (path:/tmp/pip-0gnawr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:53:28 INFO - Running setup.py (path:/tmp/pip-PJG1JP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:53:28 INFO - Running setup.py (path:/tmp/pip-8FUEnQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:53:28 INFO - Running setup.py (path:/tmp/pip-cgWpQG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:53:28 INFO - Running setup.py (path:/tmp/pip-P7uKUu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:53:28 INFO - Running setup.py (path:/tmp/pip-IGrnOA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:53:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:53:28 INFO - Running setup.py (path:/tmp/pip-MvH8Cg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:53:29 INFO - Running setup.py (path:/tmp/pip-3E6806-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:53:29 INFO - Running setup.py (path:/tmp/pip-u6Qu_o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:53:29 INFO - Running setup.py (path:/tmp/pip-34DQUT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:53:29 INFO - Running setup.py (path:/tmp/pip-pUPqDJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:53:29 INFO - Running setup.py (path:/tmp/pip-oaRGRw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:53:29 INFO - Running setup.py (path:/tmp/pip-kNtQVf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:53:29 INFO - Running setup.py (path:/tmp/pip-VT0sXu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:53:29 INFO - Running setup.py (path:/tmp/pip-dIe7sj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:53:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:53:29 INFO - Running setup.py (path:/tmp/pip-QGt5Qh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:53:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:29 INFO - Running setup.py install for manifestparser 08:53:29 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:53:29 INFO - Running setup.py install for mozcrash 08:53:29 INFO - Running setup.py install for mozdebug 08:53:29 INFO - Running setup.py install for mozdevice 08:53:30 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for mozfile 08:53:30 INFO - Running setup.py install for mozhttpd 08:53:30 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for mozinfo 08:53:30 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for mozInstall 08:53:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for mozleak 08:53:30 INFO - Running setup.py install for mozlog 08:53:30 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for moznetwork 08:53:30 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:53:30 INFO - Running setup.py install for mozprocess 08:53:30 INFO - Running setup.py install for mozprofile 08:53:31 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:53:31 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:53:31 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:53:31 INFO - Running setup.py install for mozrunner 08:53:31 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:53:31 INFO - Running setup.py install for mozscreenshot 08:53:31 INFO - Running setup.py install for moztest 08:53:31 INFO - Running setup.py install for mozversion 08:53:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:53:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:31 INFO - Cleaning up... 08:53:31 INFO - Return code: 0 08:53:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:31 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 08:53:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/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 08:53:31 INFO - Using env: {'DISPLAY': ':0', 08:53:31 INFO - 'HOME': '/home/cltbld', 08:53:31 INFO - 'LANG': 'en_US.UTF-8', 08:53:31 INFO - 'LANGUAGE': 'en_US:en', 08:53:31 INFO - 'LOGNAME': 'cltbld', 08:53:31 INFO - 'MAIL': '/var/mail/cltbld', 08:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:31 INFO - 'MOZ_NO_REMOTE': '1', 08:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:31 INFO - 'NO_EM_RESTART': '1', 08:53:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:31 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:31 INFO - 'SHELL': '/bin/bash', 08:53:31 INFO - 'SHLVL': '1', 08:53:31 INFO - 'TERM': 'linux', 08:53:31 INFO - 'TMOUT': '86400', 08:53:31 INFO - 'USER': 'cltbld', 08:53:31 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:31 INFO - '_': '/tools/buildbot/bin/python'} 08:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:53:31 INFO - Running setup.py (path:/tmp/pip-L4PS9_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:53:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 08:53:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:53:31 INFO - Running setup.py (path:/tmp/pip-tiJgY3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:53:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:53:31 INFO - Running setup.py (path:/tmp/pip-MtZeIQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:53:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:53:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:53:31 INFO - Running setup.py (path:/tmp/pip-TCuanm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:53:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:53:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:53:31 INFO - Running setup.py (path:/tmp/pip-UUJX7Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:53:32 INFO - Running setup.py (path:/tmp/pip-kJkEPH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:53:32 INFO - Running setup.py (path:/tmp/pip-MybDu_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:53:32 INFO - Running setup.py (path:/tmp/pip-PQ_QZA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:53:32 INFO - Running setup.py (path:/tmp/pip-ZyJYNX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:53:32 INFO - Running setup.py (path:/tmp/pip-0FWtre-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:53:32 INFO - Running setup.py (path:/tmp/pip-amzx8n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:53:32 INFO - Running setup.py (path:/tmp/pip-n_aPQK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:53:32 INFO - Running setup.py (path:/tmp/pip-5H4gbv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:53:32 INFO - Running setup.py (path:/tmp/pip-3ClrQZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:53:32 INFO - Running setup.py (path:/tmp/pip-F3TvFN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:53:32 INFO - Running setup.py (path:/tmp/pip-ozbJbg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 08:53:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:53:32 INFO - Running setup.py (path:/tmp/pip-3VXIDO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:53:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:34 INFO - Downloading blessings-1.6.tar.gz 08:53:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:53:34 INFO - Installing collected packages: blessings 08:53:34 INFO - Running setup.py install for blessings 08:53:34 INFO - Successfully installed blessings 08:53:34 INFO - Cleaning up... 08:53:34 INFO - Return code: 0 08:53:34 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:53:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:53:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:53:35 INFO - Reading from file tmpfile_stdout 08:53:35 INFO - Current package versions: 08:53:35 INFO - argparse == 1.2.1 08:53:35 INFO - blessings == 1.6 08:53:35 INFO - blobuploader == 1.2.4 08:53:35 INFO - docopt == 0.6.1 08:53:35 INFO - manifestparser == 1.1 08:53:35 INFO - mozInstall == 1.12 08:53:35 INFO - mozcrash == 0.16 08:53:35 INFO - mozdebug == 0.1 08:53:35 INFO - mozdevice == 0.47 08:53:35 INFO - mozfile == 1.2 08:53:35 INFO - mozhttpd == 0.7 08:53:35 INFO - mozinfo == 0.9 08:53:35 INFO - mozleak == 0.1 08:53:35 INFO - mozlog == 3.1 08:53:35 INFO - moznetwork == 0.27 08:53:35 INFO - mozprocess == 0.22 08:53:35 INFO - mozprofile == 0.27 08:53:35 INFO - mozrunner == 6.11 08:53:35 INFO - mozscreenshot == 0.1 08:53:35 INFO - mozsystemmonitor == 0.0 08:53:35 INFO - moztest == 0.7 08:53:35 INFO - mozversion == 1.4 08:53:35 INFO - psutil == 3.1.1 08:53:35 INFO - requests == 1.2.3 08:53:35 INFO - wsgiref == 0.1.2 08:53:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 08:53:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/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 08:53:35 INFO - Using env: {'DISPLAY': ':0', 08:53:35 INFO - 'HOME': '/home/cltbld', 08:53:35 INFO - 'LANG': 'en_US.UTF-8', 08:53:35 INFO - 'LANGUAGE': 'en_US:en', 08:53:35 INFO - 'LOGNAME': 'cltbld', 08:53:35 INFO - 'MAIL': '/var/mail/cltbld', 08:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:35 INFO - 'MOZ_NO_REMOTE': '1', 08:53:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:35 INFO - 'NO_EM_RESTART': '1', 08:53:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:35 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:35 INFO - 'SHELL': '/bin/bash', 08:53:35 INFO - 'SHLVL': '1', 08:53:35 INFO - 'TERM': 'linux', 08:53:35 INFO - 'TMOUT': '86400', 08:53:35 INFO - 'USER': 'cltbld', 08:53:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:35 INFO - '_': '/tools/buildbot/bin/python'} 08:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:53:35 INFO - Cleaning up... 08:53:35 INFO - Return code: 0 08:53:35 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:35 INFO - Running command: ['/builds/slave/test-pgo/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-pgo/build 08:53:35 INFO - Copy/paste: /builds/slave/test-pgo/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 08:53:35 INFO - Using env: {'DISPLAY': ':0', 08:53:35 INFO - 'HOME': '/home/cltbld', 08:53:35 INFO - 'LANG': 'en_US.UTF-8', 08:53:35 INFO - 'LANGUAGE': 'en_US:en', 08:53:35 INFO - 'LOGNAME': 'cltbld', 08:53:35 INFO - 'MAIL': '/var/mail/cltbld', 08:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:35 INFO - 'MOZ_NO_REMOTE': '1', 08:53:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:35 INFO - 'NO_EM_RESTART': '1', 08:53:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:35 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:35 INFO - 'SHELL': '/bin/bash', 08:53:35 INFO - 'SHLVL': '1', 08:53:35 INFO - 'TERM': 'linux', 08:53:35 INFO - 'TMOUT': '86400', 08:53:35 INFO - 'USER': 'cltbld', 08:53:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:35 INFO - '_': '/tools/buildbot/bin/python'} 08:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:35 INFO - Downloading/unpacking jsonschema 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:53:37 INFO - Downloading/unpacking functools32 (from jsonschema) 08:53:37 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 08:53:37 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 08:53:37 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 08:53:37 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 08:53:37 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 08:53:37 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 08:53:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:53:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:53:37 INFO - warning: no files found matching '*.txt' 08:53:37 INFO - no previously-included directories found matching 'build' 08:53:37 INFO - no previously-included directories found matching 'dist' 08:53:37 INFO - no previously-included directories found matching '.git*' 08:53:37 INFO - Installing collected packages: jsonschema, functools32 08:53:37 INFO - Running setup.py install for functools32 08:53:37 INFO - warning: no files found matching '*.txt' 08:53:37 INFO - no previously-included directories found matching 'build' 08:53:37 INFO - no previously-included directories found matching 'dist' 08:53:37 INFO - no previously-included directories found matching '.git*' 08:53:37 INFO - Successfully installed jsonschema functools32 08:53:37 INFO - Cleaning up... 08:53:37 INFO - Return code: 0 08:53:37 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5caf26f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16d18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x179e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 08:53:37 INFO - Running command: ['/builds/slave/test-pgo/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', 'flake8'] in /builds/slave/test-pgo/build 08:53:37 INFO - Copy/paste: /builds/slave/test-pgo/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 flake8 08:53:37 INFO - Using env: {'DISPLAY': ':0', 08:53:37 INFO - 'HOME': '/home/cltbld', 08:53:37 INFO - 'LANG': 'en_US.UTF-8', 08:53:37 INFO - 'LANGUAGE': 'en_US:en', 08:53:37 INFO - 'LOGNAME': 'cltbld', 08:53:37 INFO - 'MAIL': '/var/mail/cltbld', 08:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:37 INFO - 'MOZ_NO_REMOTE': '1', 08:53:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:37 INFO - 'NO_EM_RESTART': '1', 08:53:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:37 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:37 INFO - 'SHELL': '/bin/bash', 08:53:37 INFO - 'SHLVL': '1', 08:53:37 INFO - 'TERM': 'linux', 08:53:37 INFO - 'TMOUT': '86400', 08:53:37 INFO - 'USER': 'cltbld', 08:53:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:37 INFO - '_': '/tools/buildbot/bin/python'} 08:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:38 INFO - Downloading/unpacking flake8 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:38 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 08:53:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:53:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:53:39 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 08:53:39 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 08:53:39 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 08:53:39 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 08:53:39 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 08:53:39 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 08:53:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:53:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 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 08:53:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:53:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:53:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:53:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:53:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:53:40 INFO - no previously-included directories found matching 'docs/_build' 08:53:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:53:40 INFO - Running setup.py install for pep8 08:53:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:53:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:53:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:53:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:53:40 INFO - no previously-included directories found matching 'docs/_build' 08:53:40 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:53:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:53:40 INFO - Cleaning up... 08:53:40 INFO - Return code: 0 08:53:40 INFO - Running post-action listener: _resource_record_post_action 08:53:40 INFO - Running post-action listener: _start_resource_monitoring 08:53:40 INFO - Starting resource monitoring. 08:53:40 INFO - ##### 08:53:40 INFO - ##### Running install step. 08:53:40 INFO - ##### 08:53:40 INFO - Running pre-action listener: _resource_record_pre_action 08:53:40 INFO - Running main action method: install 08:53:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:53:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:53:41 INFO - Reading from file tmpfile_stdout 08:53:41 INFO - Detecting whether we're running mozinstall >=1.0... 08:53:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:53:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:53:41 INFO - Reading from file tmpfile_stdout 08:53:41 INFO - Output received: 08:53:41 INFO - Usage: mozinstall [options] installer 08:53:41 INFO - Options: 08:53:41 INFO - -h, --help show this help message and exit 08:53:41 INFO - -d DEST, --destination=DEST 08:53:41 INFO - Directory to install application into. [default: 08:53:41 INFO - "/builds/slave/test-pgo"] 08:53:41 INFO - --app=APP Application being installed. [default: firefox] 08:53:41 INFO - mkdir: /builds/slave/test-pgo/build/application 08:53:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:53:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:53:55 INFO - Reading from file tmpfile_stdout 08:53:55 INFO - Output received: 08:53:55 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:53:55 INFO - Running post-action listener: _resource_record_post_action 08:53:55 INFO - ##### 08:53:55 INFO - ##### Running run-tests step. 08:53:55 INFO - ##### 08:53:55 INFO - Running pre-action listener: _resource_record_pre_action 08:53:55 INFO - Running main action method: run_tests 08:53:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:53:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:53:55 INFO - Python 2.7.3 08:53:55 INFO - Return code: 0 08:53:55 INFO - grabbing minidump binary from tooltool 08:53: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:55 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-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x182d350>, '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 0x182e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x182e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:53:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 08:53:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:53:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:53:55 INFO - Return code: 0 08:53:55 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:53:55 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:53:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:53:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:53:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:53:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:53:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:53:56 INFO - Return code: 0 08:53:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:53:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost 08:53:56 INFO - Using env: {'DISPLAY': ':0', 08:53:56 INFO - 'HOME': '/home/cltbld', 08:53:56 INFO - 'LANG': 'en_US.UTF-8', 08:53:56 INFO - 'LANGUAGE': 'en_US:en', 08:53:56 INFO - 'LOGNAME': 'cltbld', 08:53:56 INFO - 'MAIL': '/var/mail/cltbld', 08:53:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:53:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:56 INFO - 'MOZ_NO_REMOTE': '1', 08:53:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:53:56 INFO - 'NO_EM_RESTART': '1', 08:53:56 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:53:56 INFO - 'PWD': '/builds/slave/test-pgo', 08:53:56 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:53:56 INFO - 'SHELL': '/bin/bash', 08:53:56 INFO - 'SHLVL': '1', 08:53:56 INFO - 'TERM': 'linux', 08:53:56 INFO - 'TMOUT': '86400', 08:53:56 INFO - 'USER': 'cltbld', 08:53:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190', 08:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:56 INFO - '_': '/tools/buildbot/bin/python'} 08:53:56 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost'] with output_timeout 3600 08:53:57 INFO - mozversion INFO | application_buildid: 20160225062926 08:53:57 INFO - mozversion INFO | application_changeset: 13f8d3d302cea627e2e98baf573039b4119c1743 08:53:57 INFO - mozversion INFO | application_display_name: Nightly 08:53:57 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:53:57 INFO - mozversion INFO | application_name: Firefox 08:53:57 INFO - mozversion INFO | application_remotingname: firefox 08:53:57 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:53:57 INFO - mozversion INFO | application_vendor: Mozilla 08:53:57 INFO - mozversion INFO | application_version: 45.0 08:53:57 INFO - mozversion INFO | platform_buildid: 20160225062926 08:53:57 INFO - mozversion INFO | platform_changeset: 13f8d3d302cea627e2e98baf573039b4119c1743 08:53:57 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:53:57 INFO - mozversion INFO | platform_version: 45.0 08:53:57 INFO - 2016-02-25 08:53:57,109 DEBUG : using testdate: 1456419237 08:53:57 INFO - 2016-02-25 08:53:57,109 DEBUG : actual date: 1456419237 08:53:57 INFO - 2016-02-25 08:53:57,118 INFO : starting webserver on 'localhost:38292' 08:53:57 INFO - 2016-02-25 08:53:57,119 INFO : Starting test suite talos-linux64-ix-015 08:53:57 INFO - 2016-02-25 08:53:57,119 INFO : Starting test dromaeo_css 08:53:57 INFO - 2016-02-25 08:53:57,119 DEBUG : operating with platform_type : linux_ 08:53:57 INFO - 2016-02-25 08:53:57,120 INFO : Initialising browser for dromaeo_css test... 08:53:57 INFO - 2016-02-25 08:53:57,135 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK54cq4/profile http://localhost:38292/getInfo.html 08:54:00 INFO - 2016-02-25 08:54:00,750 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:54:00 INFO - 2016-02-25 08:54:00,750 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:54:00 INFO - 2016-02-25 08:54:00,751 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:54:00 INFO - 2016-02-25 08:54:00,751 DEBUG : BROWSER_OUTPUT: __metrics 08:54:00 INFO - 2016-02-25 08:54:00,879 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:54:01 INFO - 2016-02-25 08:54:01,917 INFO : Browser initialized. 08:54:01 INFO - 2016-02-25 08:54:01,918 INFO : Running cycle 1/1 for dromaeo_css test... 08:54:01 INFO - 2016-02-25 08:54:01,918 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK54cq4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:54:13 INFO - 2016-02-25 08:54:13,628 DEBUG : BROWSER_OUTPUT: RSS: Main: 134791168 08:54:13 INFO - 2016-02-25 08:54:13,628 DEBUG : BROWSER_OUTPUT: 08:55:04 INFO - 2016-02-25 08:55:04,625 DEBUG : BROWSER_OUTPUT: 1456419304624 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] 08:57:12 INFO - 2016-02-25 08:57:12,453 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38292/tests/dromaeo/cssquery-ext.html) 08:57:13 INFO - 2016-02-25 08:57:13,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 245813248 08:57:13 INFO - 2016-02-25 08:57:13,538 DEBUG : BROWSER_OUTPUT: 09:00:15 INFO - 2016-02-25 09:00:15,633 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-ext.html (next: http://localhost:38292/tests/dromaeo/cssquery-jquery.html) 09:00:16 INFO - 2016-02-25 09:00:16,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 204992512 09:00:16 INFO - 2016-02-25 09:00:16,194 DEBUG : BROWSER_OUTPUT: 09:03:17 INFO - 2016-02-25 09:03:17,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38292/tests/dromaeo/cssquery-mootools.html) 09:03:18 INFO - 2016-02-25 09:03:18,546 DEBUG : BROWSER_OUTPUT: RSS: Main: 203583488 09:03:18 INFO - 2016-02-25 09:03:18,546 DEBUG : BROWSER_OUTPUT: 09:06:20 INFO - 2016-02-25 09:06:20,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38292/tests/dromaeo/cssquery-prototype.html) 09:06:20 INFO - 2016-02-25 09:06:20,782 DEBUG : BROWSER_OUTPUT: RSS: Main: 195334144 09:06:20 INFO - 2016-02-25 09:06:20,782 DEBUG : BROWSER_OUTPUT: 09:09:23 INFO - 2016-02-25 09:09:23,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38292/tests/dromaeo/cssquery-yui.html) 09:09:23 INFO - 2016-02-25 09:09:23,666 DEBUG : BROWSER_OUTPUT: RSS: Main: 194781184 09:09:23 INFO - 2016-02-25 09:09:23,667 DEBUG : BROWSER_OUTPUT: 09:12:25 INFO - 2016-02-25 09:12:25,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/dromaeo/cssquery-yui.html (next: http://localhost:38292/tests/dromaeo/cssquery-dojo.html) 09:12:26 INFO - 2016-02-25 09:12:26,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 241217536 09:12:26 INFO - 2016-02-25 09:12:26,038 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,038 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:12:26 INFO - 2016-02-25 09:12:26,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:12:26 INFO - 2016-02-25 09:12:26,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:12:26 INFO - 2016-02-25 09:12:26,039 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:12:26 INFO - 2016-02-25 09:12:26,039 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3259;3281.704646581807;3282.8686852525902;3288.4245257080006;3302.2239773653196;5358.223057656637;5382.596305277105;5395.91906121408;5396.730163491819;5404.7838086476495;5400.432954539776;5403.459654034605;5406.216098665691;5430.91853622195;5434.510894019538;4890.119778439879;4957.4298955620025;4962.6526143169995;5003.374578177728;5034.345535080434;4910.828121015765;4920.8031678732805;4966.826161084363;5011.799528018875;5024.196128619422;5027.899442011158;5038.345015148044;5046.940142570064;5047.570956468705;5058.190689489682;4866.902661946741;4979.0041991601465;4991.301217829507;5026.47222041684;5047.369078865142;4856.2958371036;4877.829275975359;4954.281629163787;4977.676451030671;4978;5233.162693968985;5255.448177941337;5272.578193744491;5276.630635855492;5287.603429742785;5704.914426283609;5802.535797136219;5804;5806.071028635398;5822.563307751935;5786.566007549451;5878.441548052971;5884.705764711747;5904.350521442638;5935.6438613682885;5520.641158324696;5570.637908535932;5572.7770889164385;5581.944180558224;5582.134769110754;5652.943470565324;5812.38969908162;5890.705464726832;6010.519158467312;6020.548458865515;5839.124131380327;5849.736761845727;5852.970735146297;5855.063189889597;5867.794627188028;5946.375630558816;5953.880922381528;5954.46409823118;5956.880862382728;5966.492848107872;5673.177389278531;5686.715664216773;5796.101604251347;5796.681182535001;5891.646501209942;5829.795957141565;5838.416158384128;5866.794662186803;5871.119332100219;5872.677002764804;4565;4576.107659006529;4578.244589642738;4578.2903649934315;4597.678162528591;3997.9400308995387;4020.7788571628257;4076.3885417187657;4098.569650186688;4105.220008198433;5457.836264912059;5522.337319521603;5526;5529.447055294519;5557.972210139004;5748.39641837601;5851.912221316684;5877;5877.059670452707;5877.882442351129;5346.358436987586;5397.892042159214;5420.430854760272;5437.293151890308;5473.945260547343;5052.747362631853;5137.537621614073;5138.409082955508;5140.614453915897;5179.223116532475;5849.034909239927;5964.940350596438;6091.604045737013;6097.085437184369;6103.938960610337;5665.631733937281;5751.568632352674;5757.309122905278;5757.79847705345;5759.366469688247;6074.058520929263;6145.569810113249;6146.078088286793;6147.815565532862;6150.938490615215;5628.690422026664;5637.802676906289;5742.196092546963;5810.128480727924;5835.358110607915;5791.449812267828;5805.506531944663;5830.708464576839;5832.270966129234;5898.085796701518;5668.971655141698;5683.602147849776;5685;5686.033374326457;5691.544676425793;5566.4990150886615;5636.830895073154;5660.462256085665;5708.200851880823;5811.4188581143235;5579.972100139474;5607.074832652566;5618.213450116905;5619.8876022480135;5627.296587926509;5704.771809127755;5722.62802917809;5723.828285151452;5727.36998930109;5728.770849166153;5505.6696598204235;5509.393966663583;5568.972155139199;5591.8881622368135;5607.74765135578 09:12:26 INFO - 2016-02-25 09:12:26,042 DEBUG : BROWSER_OUTPUT: |1;ext.html;551.3218740948633;605.2132228103466;607.6414915200031;611.2176414189838;611.6269075863726;32838.507422388655;41058;41372.17255654885;41526.584734152646;41546.37680434792;45317.32024019642;45625.54374456263;45876.54123458765;45891.31163032549;45909.77045114778;8138.470999385021;9117;9294.814103717921;9297.860532092023;9303.674371397003;8224.83550328993;10937.726556836104;11163.441827908613;11167.609133680322;11179.385133817637;10852.511636976316;10979.121670266359;10982.231243812936;10984.725381865437;10986.340819550853;10779.514921828497;10837.67486975384;10854.45727713611;10855.620053298175;10870.076043536308;10493.160547156209;10626.840597391045;10635.308704934154;10635.680929572125;10638.361698298128;42194.78902605467;42596;42618.57381426177;42651;42684.78657606692;3717.3308804415196;3759.3984962405884;3771.9434208486896;4354.564543545652;4375.234333991571;32337.51493727596;32385.676143238503;32503.51244731331;32570.02289931306;33691.15772105692;4280.978595107035;4332.91334173318;4348.9782551087355;4390.297552391602;4458.777061146962;286239;287806;288025;288337;401163.99418003013;50701.49298506968;61064;62340.37659623437;62552.99999999909;62641;55832.72083639556;56371.436285637436;56389.43610563924;56390.026349078405;56396;16134.435294764628;16373.345066197218;16394.83605163933;16399.508014759576;16407.343706251853;12399.628011159679;12622.684432889251;12645.304508251951;12659.050571207197;13012.544560940321;19855.205791768167;20123.899380503295;20165.49586260326;20178.19287228524;20222.898885505772;7775.105862825847;7952.409518096404;8052.611326826894;8077.798055048671;8144.918550814535;1017.9210037360422;1072.7747173093737;1094.7482079121846;1112.6105862948066;1164.4119719541577;29590.852045739633;30077.398452031273;30138;30514.389712205633;30687.693123068482;1136.5567428702811;1140.9365852685567;1165.2891736041008;1175.506287359311;1232.9951089861734;13752.65618359549;15340.539783806504;15518.689626207413;15556.222188890737;16012.759808602881;333692;339754;341184;341342;342227;56225.718871405385;56596.15105773417;56625.43374566284;56640;56749.71625141848;1407.5917983784793;1410.8659677330638;1462.5612316305278;1496.8203815542204;1559.50095969291;27730;28772.42455150927;29268.853655731586;29278.70721292845;29421;53035;53181.46818531765;53808;53996.46003539914;54247;261947;262903;264474;264737;265140;33732.83133584316;33800;33807.15482112967;33887.66112338944;34130.82934585411;46543.767281163375;46599.30101048419;46710;46837.76581117073;47356.763216183695;32879.83560082184;32972.67027329696;34211.48682769711;34715.826420867736;34897.82551087228;34022.48966265458;34074.659253407146;34695.13262168466;37248.44127338038;37494;36558.8172059138;36798.71204507937;37167.25665486729;37346.813265933495;37723.81138094292;31377.52933706042;33427;34377;34511.30977380489;34516;34450.65549344474;35142.12144696403;36713.632863671024;36743.26513469662;36745.816270918476 09:12:26 INFO - 2016-02-25 09:12:26,042 DEBUG : BROWSER_OUTPUT: |2;jquery.html;373.65250317205;374.34115955917594;377.6525596451265;380.06314434917937;381.36883457877224;14962.17708026058;17056.91471542642;17382.913085434586;18996.33512827051;19053.333133340337;17111.486655400357;17248;17412.47762567125;17432.38986635465;17457.1271436428;4307.418498502709;5543.085390910495;5653.321601407837;5672.631278966875;5673.26247587813;4400.383946247528;6095.116208149838;6190.3500132486115;6191.473724733403;6207.875842483148;5491.972540137313;5622.943770562303;6103.084537319393;6112.6638034908065;6127.81616551502;5565.6104072714925;5567.081431563787;5569.749361278752;5587.05020146574;5588.804391846266;5395.460453954613;5417.052015897202;5417.45825417459;5513.751881165357;5522.364928033247;13943.511977080754;14061.367238474293;14078.2960851957;14113.294335283297;14266;2445.572024895654;2487.4154573675173;2487.5522405966917;2520.231329444519;2539.2509209783157;13379.130356526892;13573.728525429535;13940.512082077079;14049.367778449994;14295.78556321656;1916.48254971157;1916.6933290673564;1929.5754933914516;1934.1586409911733;1943.319838056683;62326.68836655788;66154;66338;67440.32559674438;68342.3165768341;10915.890841091488;11131.72170695739;11139.665810025712;11183.72040698989;11249.381284029458;14829.480968166063;14889.70220595582;14892.255387230594;14905.478308259158;14921.850781492263;6842.657867106725;7125.92874071263;7149.213586505481;7543.283388078124;7586.734464293833;7069.823254418578;7275.781726548212;7513;7548.018757561482;7568.318851303409;4430.933535996963;4557.589816916494;4639.373684552542;4660.603848672833;4874.780634871439;1858.823411775879;1887.5186827358937;1904.0955546115483;1920.9423717288503;1952.0434986856621;961.3510880155867;973.454865275448;992.9205663546899;993.6720882108887;1033.715728174758;14915.627109322137;17033.7444938326;17048.57378565546;17168.22742976569;17542.736858947126;1017.0439786600572;1017.2167431078028;1031.1235449867702;1105.8617672790901;1119.1270808769173;9784.657536986171;10101.747456313651;10109.140723038625;10127.949360253151;10142.239332050125;575302;575407;589871;592445;597234;79208.60395697984;80821.59589202017;82008.58995704983;82494.58752706199;84335.57832210923;1194.1879521925;1196.108898870347;1196.108898870347;1201.2672269915345;1207.5592408770735;15508;15949;16012;16045.518634440985;16140.838591613934;34090.65909340875;34240.315193696486;34300.828495857364;34897.30205395928;37550.43674344832;46403.30395044145;46600.533994660975;46687.53312466832;46873.296900547204;47331.29003064888;10342.793144137226;10403.635872744419;10413.427261500387;10584.89415105839;10607.204459665401;14862.925685371503;14901.552953411416;16550.50348489547;17627.55931101733;18036;14318.57044288673;14919.701605967603;14977.475788347356;15037.097774133586;15490.690186196438;14587.635309116933;14788.334524946187;14876.553703388916;15129.167895765839;15220;15302.693946121239;15437.536873893801;15665.843341566439;15715.921420392824;15746.685066298838;9962.950185249027;10148;10490.685279441628;10774.892251077703;10788.622398216025;12131.939340303243;15162.620934476727;15209.6197595061;15679.902406831869;15923.920380398487 09:12:26 INFO - 2016-02-25 09:12:26,044 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1558.9220538973057;1561.7501199808025;1764.6206065695874;1803.5851754096557;1847.076461769115;7544.547327160368;7892.842143157133;7894.250046245605;7897.40769442292;7912.169222231655;6893.620850853201;7022.36798688118;7250.057492525977;7389.261073892608;7793.688252469909;2325.244295603927;2341.496578235679;2365.7161140663143;2365.7279412867483;2366.018102487474;2039.1945181653239;2331.160782118436;2363.7518060603647;2374.548835721216;2375.928722138339;2049.3646969439446;2304.758000409958;2344.800691941187;2347.084636991575;2347.1315613223046;2227.9108835646553;2234.977650223493;2236.7763223677666;2299.2297580310615;2300.953980920372;2279.361778701966;2299.1378233162563;2323.9767602323927;2324.8837558122027;2325.081592770863;7670.386369090459;7769.689212431553;7785.610719464004;7789.922100779033;7791.065072191317;4720.504347043546;4799.328094066834;4813.735244561548;4814.157522433595;4814.590759785422;3083.814971101741;3104.1153271317567;3105.4254962831724;3130.98434507826;3251.349730053999;1097.5609756097545;1122.3770807202;1130.0507573638183;2105.0948092320295;2123.3205374280237;10575.20685948557;10688.51901664427;10707.357558546435;10715.142788576895;10719.088877445427;4123.7216462896595;4349.586789255016;4430.446194225722;4462.598366146997;4469.10617876426;8661.870071948926;8725.476471411735;8779.956100219459;8796.34027447944;8810;4847.224444088929;4854.587360074363;4855.902881942341;4856.902861942742;4858.46556878743;4406.691531592822;4459.019015816515;4466.196084704719;4466.665000125004;4496.033352829092;3534.6111927687934;3541.8229088545468;3553.697935675445;3573.2138929436;3576.034470692898;1029.3875144289136;1052.742078190833;1078.4823284823328;1083.2254937719567;1088.0479580367178;1122.6351435783404;1132.6375559820942;1179.021412227866;1360.5170164591427;1425.62933637253;7263.164736055315;7387.076615423072;7455;7465.589392583352;7492.288232617892;654.8395643067383;663.4824836627438;665.4609766089386;670.8658268346351;810.1979040749733;5726.284214473191;5739.225204597409;5747.942520574825;5756.913646295309;5760.596758226968;9053.864192037125;9113.863292050624;9202.723918282461;9387;9389;9232.446053236827;9375.34372593912;9394.718158455258;9399.436033837992;9403.106704863027;7104.68028938699;7270.272972702687;7298.124225093023;7311.597862117635;7380.520266182681;7286.817829554304;7293.088363954505;7329.230430804689;7330.230325815713;7334.926650733424;8150.592470376576;8322.417430779788;8455.74632761018;8480.872786908078;8481.957590212009;8426.283765879969;8560.614772335135;8566.314694824274;8638.308935285288;8679.479231246145;6764.966175169093;6786.796396108125;6855.143107111611;6879.6560171992205;6880.174379074543;7446.851062978819;7450.850982980419;7481.177070522347;7490;7494.962525187558;6068.393160683896;6995.860082798417;7232.63836808168;7313.414926805949;7382.114146302478;7087.929120708934;7110.502264841618;7139.714411423485;7183.712651494091;7209.423246140401;7278.854422911618;7311.817204569928;7314.122305323182;7314.926850731425;7317.341439270491;6615.834604134935;6629.071929929717;6648.368405001517;6656.866862662817;6669.833254168685;6418.229812422539;6458;6461.935380646321;6469.676516174267;6480.967595161994 09:12:26 INFO - 2016-02-25 09:12:26,044 DEBUG : BROWSER_OUTPUT: |4;prototype.html;347.49786558423074;360.70782666040526;361.96199399063096;362.5160410851513;371.8159511042033;6133.049377346507;6168.167297414848;6178.814635560935;6202.914489964255;6221.564490485668;5806.796762113327;5934.851628709273;5988.041913293874;6065.575409721321;6068.39316068393;4746.813296675831;4748.643851711118;4756.690815097017;4758.072175925696;4781.426228852542;4562;4766.833160839371;4773.856784296477;4775.641826862981;4786.138495070903;4611.216093264136;4622.07558488302;4719.528047195287;4727.598154156901;4730.574248317651;4684.063187362541;4716.2454007359;4721.126591580569;4721.929171062454;4722.693024953367;4207.747535147901;4209.221294060609;4209.326507758744;4215.91568168638;4217.219814334328;5743.310802703661;5797.768089276424;5812.709364531756;5814.156947242626;5827.213326200951;5515.448455154493;5517.227588137664;5521.834344969658;5528.336599608073;5531.9723401382735;6405.93594064058;6412.903806442907;6415.518836087265;6417.711202995876;6427.485801135945;4663.300504924255;4668.929966050512;4669.136209801198;4671;4674.906501869978;6529.804105876831;6686.331366863324;6687.230968438595;6688.598684078971;6695.196576410814;4776.14029474691;4778.259369797687;4779.593734532604;4781.163296423112;4782.617390608743;4848.369711937426;4850.345203397496;4851.708897466093;4852.4662287148385;4855.441624213261;5416.91874621881;5482.588805839578;5487.368952570426;5489.1217405215775;5493.945060549343;6449.516286278548;6485.805425837232;6489.643069631215;6501.44737697301;6501.609903405811;1667.483080245128;1686.6289416328389;1690;1690.5351028467269;1690.7971043474615;1142.4459137318395;1149.844770955927;1164.016406136813;1171.8183681529376;1182.698411904976;6280.874382512324;6286;6294.464970477469;6295.118683384329;6295.527835412362;6510.18622672166;6702.731890724414;6718.32816718334;6720.126383570202;6727.529072964943;6189.721462534197;6221.688915554204;6341.524385671093;6347.809565713036;6351.333110023381;3564.3049605327233;3644.945325820062;3645.434957581579;3645.8359373828243;3646.9817650912105;6787.355201255873;6927.3419025192525;6927.445804335742;6927.58434493932;6934.618595977169;6916.827079322957;6924.826879327957;6925.480588955848;6927.653617319113;6930.861382772317;6965.303469653096;7004.054452648929;7004.089468369079;7011.298870112948;7015.158181018311;6665.600063996176;6758;6767.89848152288;6768.763093291711;6769.627670477974;6611.966940165268;6615.338466153346;6643.966780166068;6682.465402767864;6684.4652427804685;6896.586204827727;6922.653867306715;6954.756583519553;6993.4055605274125;6998.090248267693;4505.7296562206375;4829.541193586604;4832.830850920342;4833.3958255218095;4835.2505361669;6694.966525167343;6743.325667433218;6746.595204287562;6748.392644662004;6749.865002700017;6560;6561.934380656132;6564.048213009182;6564.606123632598;6567.573107747981;6414.93585064162;6528.934710652833;6546.574472659262;6549.901751473827;6554.180727409074;6360.4911607072245;6411.5832470889245;6413.4869210463985;6413.967930160319;6421.293657697743;6546.509011824037;6547.803565893031;6549.770758023446;6550.213974323112;6552;6306.1801965746;6405.839854003687;6409.9038514423255;6414.871702566016;6423.871522569429 09:12:26 INFO - 2016-02-25 09:12:26,045 DEBUG : BROWSER_OUTPUT: |5;yui.html;934.5560858592165;941.4492521874703;942.2627267787079;949.1789601994275;955.5270141280067;14425.567232983014;14684.339204735787;14703.852961470395;14728.926355368209;14735.852641473595;12675.809862852064;12861.871381286184;12928.09503334767;12935.676608084803;12946.223226606411;752.5259086775324;767.2059418501851;883.5493898111961;891.1934699097295;903.4850135422812;913.4108500017485;914.5335878701843;923.5059243304834;923.8152369526088;923.9815203695922;957.7509847439646;968.9467079310635;970.2141265574871;970.5389939778584;971.0677749360588;783.4946459533597;798.4211446701124;930.7580029192425;952.7713348796308;960.9039096090335;710.9217986021533;712.7647876200841;713.6859781696037;714.253604982789;714.4177495341332;13768.518101866486;13967.580972570839;13970.860291397057;13971.79042314366;13981.370838312301;11994.760104797942;12343.455351500903;12388.81416778749;12399.380030998414;12623.621291361182;10517.05346518817;10591.810445586163;10625.521851516623;10645.627403040933;10660.520276587573;3854.1520865409566;3946.2304850554156;3952.071263253133;3958.604139586047;3958.604139586047;49176.067214122464;49723.75138124322;49810.25284620734;49817;49879.75060124713;9741.123298903132;9858.802823943624;9860.112589866947;9917;10000.099991000845;18156;18174.5456363592;18181.18184681666;18203.544911377056;19192.328268510537;7606.8098297543;7693.692252309845;7705.4220933430215;7726.38188944883;7767.92232077672;4231.048014196781;4248.4901811782165;4304.203722311382;4329.653627709776;4340.739555626609;3242.578464799561;3274.5088236764677;3274.9345013099605;3276.3283526876935;3278.8032718036516;585.1018686316569;595.4849055566904;600.9699515024231;609.1228630771675;610.1305639463747;658.26932105363;662.3707477895922;677.8745932002497;711.2922641971272;721.0878239027634;8927.151920567536;8958;8972.147645973622;8973.147550982647;9050.954745226363;684.3772167327808;711.3668834737089;738.4978214813979;748.554610007045;770.622395026433;4015.759054456742;4248.532661407243;4273.273543497613;4542.409486766699;4638.652101092432;58742.41257587455;59983.700081499315;59993.40006599965;60184;60794.69602651958;51079.59588706368;51750.22374664383;52469.7376513115;52805.47194528006;54481.1827822583;48468.51531484711;48901;49590.25614615784;49657;50065.24902126471;9270.212031977357;9396.154346108882;9447.102525260088;9447.291453140904;9524.190443812076;35403.64596354003;35494.29011419809;36622.633773661924;37841;37983.81008094942;46783;47155.76422118005;47306.76346618245;47325;47668;11568.768624627292;11594.246373985665;11759.647210583697;11783.293002419883;12069.879301207227;12296.262224266573;12694.746105078031;12936.676583085498;12999.935000324938;13125.474981000652;11452.713682158013;11522.135839812216;11535.653930382103;11936;12021.278723276631;11570.132240081859;11824.46789894474;12028.398580071136;12103.213291136048;12680.365981701063;10316.43259620728;11544.480498377761;11874.168808183344;11965.880341196476;12176.269423834598;10277.177825773771;10313.639022634172;10481.790364192826;10502.842457362993;10539.156867450432;9604.135627793532;10189.235807314333;10249.846252306072;10382;10393.428361440194 09:12:26 INFO - 2016-02-25 09:12:26,045 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:12:26 INFO - 2016-02-25 09:12:26,045 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:12:26 INFO - 2016-02-25 09:12:26,045 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,820 09:12:26 INFO - 2016-02-25 09:12:26,045 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1456420346036__endTimestamp 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5425.35 Median:5607.75 stddev:588.73 (10.5%) stddev-sans-first:565.47 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: Values: 3259.0 3281.7 3282.9 3288.4 3302.2 5358.2 5382.6 5395.9 5396.7 5404.8 5400.4 5403.5 5406.2 5430.9 5434.5 4890.1 4957.4 4962.7 5003.4 5034.3 4910.8 4920.8 4966.8 5011.8 5024.2 5027.9 5038.3 5046.9 5047.6 5058.2 4866.9 4979.0 4991.3 5026.5 5047.4 4856.3 4877.8 4954.3 4977.7 4978.0 5233.2 5255.4 5272.6 5276.6 5287.6 5704.9 5802.5 5804.0 5806.1 5822.6 5786.6 5878.4 5884.7 5904.4 5935.6 5520.6 5570.6 5572.8 5581.9 5582.1 5652.9 5812.4 5890.7 6010.5 6020.5 5839.1 5849.7 5853.0 5855.1 5867.8 5946.4 5953.9 5954.5 5956.9 5966.5 5673.2 5686.7 5796.1 5796.7 5891.6 5829.8 5838.4 5866.8 5871.1 5872.7 4565.0 4576.1 4578.2 4578.3 4597.7 3997.9 4020.8 4076.4 4098.6 4105.2 5457.8 5522.3 5526.0 5529.4 5558.0 5748.4 5851.9 5877.0 5877.1 5877.9 5346.4 5397.9 5420.4 5437.3 5473.9 5052.7 5137.5 5138.4 5140.6 5179.2 5849.0 5964.9 6091.6 6097.1 6103.9 5665.6 5751.6 5757.3 5757.8 5759.4 6074.1 6145.6 6146.1 6147.8 6150.9 5628.7 5637.8 5742.2 5810.1 5835.4 5791.4 5805.5 5830.7 5832.3 5898.1 5669.0 5683.6 5685.0 5686.0 5691.5 5566.5 5636.8 5660.5 5708.2 5811.4 5580.0 5607.1 5618.2 5619.9 5627.3 5704.8 5722.6 5723.8 5727.4 5728.8 5505.7 5509.4 5569.0 5591.9 5607.7 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,046 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48714.95 Median:187629.33 stddev:80210.86 (42.7%) stddev-sans-first:80354.35 09:12:26 INFO - 2016-02-25 09:12:26,048 DEBUG : BROWSER_OUTPUT: Values: 551.3 605.2 607.6 611.2 611.6 32838.5 41058.0 41372.2 41526.6 41546.4 45317.3 45625.5 45876.5 45891.3 45909.8 8138.5 9117.0 9294.8 9297.9 9303.7 8224.8 10937.7 11163.4 11167.6 11179.4 10852.5 10979.1 10982.2 10984.7 10986.3 10779.5 10837.7 10854.5 10855.6 10870.1 10493.2 10626.8 10635.3 10635.7 10638.4 42194.8 42596.0 42618.6 42651.0 42684.8 3717.3 3759.4 3771.9 4354.6 4375.2 32337.5 32385.7 32503.5 32570.0 33691.2 4281.0 4332.9 4349.0 4390.3 4458.8 286239.0 287806.0 288025.0 288337.0 401164.0 50701.5 61064.0 62340.4 62553.0 62641.0 55832.7 56371.4 56389.4 56390.0 56396.0 16134.4 16373.3 16394.8 16399.5 16407.3 12399.6 12622.7 12645.3 12659.1 13012.5 19855.2 20123.9 20165.5 20178.2 20222.9 7775.1 7952.4 8052.6 8077.8 8144.9 1017.9 1072.8 1094.7 1112.6 1164.4 29590.9 30077.4 30138.0 30514.4 30687.7 1136.6 1140.9 1165.3 1175.5 1233.0 13752.7 15340.5 15518.7 15556.2 16012.8 333692.0 339754.0 341184.0 341342.0 342227.0 56225.7 56596.2 56625.4 56640.0 56749.7 1407.6 1410.9 1462.6 1496.8 1559.5 27730.0 28772.4 29268.9 29278.7 29421.0 53035.0 53181.5 53808.0 53996.5 54247.0 261947.0 262903.0 264474.0 264737.0 265140.0 33732.8 33800.0 33807.2 33887.7 34130.8 46543.8 46599.3 46710.0 46837.8 47356.8 32879.8 32972.7 34211.5 34715.8 34897.8 34022.5 34074.7 34695.1 37248.4 37494.0 36558.8 36798.7 37167.3 37346.8 37723.8 31377.5 33427.0 34377.0 34511.3 34516.0 34450.7 35142.1 36713.6 36743.3 36745.8 09:12:26 INFO - 2016-02-25 09:12:26,048 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,048 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30615.12 Median:10478.71 stddev:95713.12 (913.4%) stddev-sans-first:95954.68 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: Values: 373.7 374.3 377.7 380.1 381.4 14962.2 17056.9 17382.9 18996.3 19053.3 17111.5 17248.0 17412.5 17432.4 17457.1 4307.4 5543.1 5653.3 5672.6 5673.3 4400.4 6095.1 6190.4 6191.5 6207.9 5492.0 5622.9 6103.1 6112.7 6127.8 5565.6 5567.1 5569.7 5587.1 5588.8 5395.5 5417.1 5417.5 5513.8 5522.4 13943.5 14061.4 14078.3 14113.3 14266.0 2445.6 2487.4 2487.6 2520.2 2539.3 13379.1 13573.7 13940.5 14049.4 14295.8 1916.5 1916.7 1929.6 1934.2 1943.3 62326.7 66154.0 66338.0 67440.3 68342.3 10915.9 11131.7 11139.7 11183.7 11249.4 14829.5 14889.7 14892.3 14905.5 14921.9 6842.7 7125.9 7149.2 7543.3 7586.7 7069.8 7275.8 7513.0 7548.0 7568.3 4430.9 4557.6 4639.4 4660.6 4874.8 1858.8 1887.5 1904.1 1920.9 1952.0 961.4 973.5 992.9 993.7 1033.7 14915.6 17033.7 17048.6 17168.2 17542.7 1017.0 1017.2 1031.1 1105.9 1119.1 9784.7 10101.7 10109.1 10127.9 10142.2 575302.0 575407.0 589871.0 592445.0 597234.0 79208.6 80821.6 82008.6 82494.6 84335.6 1194.2 1196.1 1196.1 1201.3 1207.6 15508.0 15949.0 16012.0 16045.5 16140.8 34090.7 34240.3 34300.8 34897.3 37550.4 46403.3 46600.5 46687.5 46873.3 47331.3 10342.8 10403.6 10413.4 10584.9 10607.2 14862.9 14901.6 16550.5 17627.6 18036.0 14318.6 14919.7 14977.5 15037.1 15490.7 14587.6 14788.3 14876.6 15129.2 15220.0 15302.7 15437.5 15665.8 15715.9 15746.7 9963.0 10148.0 10490.7 10774.9 10788.6 12131.9 15162.6 15209.6 15679.9 15923.9 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5481.50 Median:3567.90 stddev:2795.04 (78.3%) stddev-sans-first:2787.33 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: Values: 1558.9 1561.8 1764.6 1803.6 1847.1 7544.5 7892.8 7894.3 7897.4 7912.2 6893.6 7022.4 7250.1 7389.3 7793.7 2325.2 2341.5 2365.7 2365.7 2366.0 2039.2 2331.2 2363.8 2374.5 2375.9 2049.4 2304.8 2344.8 2347.1 2347.1 2227.9 2235.0 2236.8 2299.2 2301.0 2279.4 2299.1 2324.0 2324.9 2325.1 7670.4 7769.7 7785.6 7789.9 7791.1 4720.5 4799.3 4813.7 4814.2 4814.6 3083.8 3104.1 3105.4 3131.0 3251.3 1097.6 1122.4 1130.1 2105.1 2123.3 10575.2 10688.5 10707.4 10715.1 10719.1 4123.7 4349.6 4430.4 4462.6 4469.1 8661.9 8725.5 8780.0 8796.3 8810.0 4847.2 4854.6 4855.9 4856.9 4858.5 4406.7 4459.0 4466.2 4466.7 4496.0 3534.6 3541.8 3553.7 3573.2 3576.0 1029.4 1052.7 1078.5 1083.2 1088.0 1122.6 1132.6 1179.0 1360.5 1425.6 7263.2 7387.1 7455.0 7465.6 7492.3 654.8 663.5 665.5 670.9 810.2 5726.3 5739.2 5747.9 5756.9 5760.6 9053.9 9113.9 9202.7 9387.0 9389.0 9232.4 9375.3 9394.7 9399.4 9403.1 7104.7 7270.3 7298.1 7311.6 7380.5 7286.8 7293.1 7329.2 7330.2 7334.9 8150.6 8322.4 8455.7 8480.9 8482.0 8426.3 8560.6 8566.3 8638.3 8679.5 6765.0 6786.8 6855.1 6879.7 6880.2 7446.9 7450.9 7481.2 7490.0 7495.0 6068.4 6995.9 7232.6 7313.4 7382.1 7087.9 7110.5 7139.7 7183.7 7209.4 7278.9 7311.8 7314.1 7314.9 7317.3 6615.8 6629.1 6648.4 6656.9 6669.8 6418.2 6458.0 6461.9 6469.7 6481.0 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5475.19 Median:6221.63 stddev:1618.48 (26.0%) stddev-sans-first:1576.59 09:12:26 INFO - 2016-02-25 09:12:26,049 DEBUG : BROWSER_OUTPUT: Values: 347.5 360.7 362.0 362.5 371.8 6133.0 6168.2 6178.8 6202.9 6221.6 5806.8 5934.9 5988.0 6065.6 6068.4 4746.8 4748.6 4756.7 4758.1 4781.4 4562.0 4766.8 4773.9 4775.6 4786.1 4611.2 4622.1 4719.5 4727.6 4730.6 4684.1 4716.2 4721.1 4721.9 4722.7 4207.7 4209.2 4209.3 4215.9 4217.2 5743.3 5797.8 5812.7 5814.2 5827.2 5515.4 5517.2 5521.8 5528.3 5532.0 6405.9 6412.9 6415.5 6417.7 6427.5 4663.3 4668.9 4669.1 4671.0 4674.9 6529.8 6686.3 6687.2 6688.6 6695.2 4776.1 4778.3 4779.6 4781.2 4782.6 4848.4 4850.3 4851.7 4852.5 4855.4 5416.9 5482.6 5487.4 5489.1 5493.9 6449.5 6485.8 6489.6 6501.4 6501.6 1667.5 1686.6 1690.0 1690.5 1690.8 1142.4 1149.8 1164.0 1171.8 1182.7 6280.9 6286.0 6294.5 6295.1 6295.5 6510.2 6702.7 6718.3 6720.1 6727.5 6189.7 6221.7 6341.5 6347.8 6351.3 3564.3 3644.9 3645.4 3645.8 3647.0 6787.4 6927.3 6927.4 6927.6 6934.6 6916.8 6924.8 6925.5 6927.7 6930.9 6965.3 7004.1 7004.1 7011.3 7015.2 6665.6 6758.0 6767.9 6768.8 6769.6 6612.0 6615.3 6644.0 6682.5 6684.5 6896.6 6922.7 6954.8 6993.4 6998.1 4505.7 4829.5 4832.8 4833.4 4835.3 6695.0 6743.3 6746.6 6748.4 6749.9 6560.0 6561.9 6564.0 6564.6 6567.6 6414.9 6528.9 6546.6 6549.9 6554.2 6360.5 6411.6 6413.5 6414.0 6421.3 6546.5 6547.8 6549.8 6550.2 6552.0 6306.2 6405.8 6409.9 6414.9 6423.9 09:12:26 INFO - 2016-02-25 09:12:26,050 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,050 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14381.81 Median:46969.38 stddev:16622.31 (35.4%) stddev-sans-first:16638.27 09:12:26 INFO - 2016-02-25 09:12:26,051 DEBUG : BROWSER_OUTPUT: Values: 934.6 941.4 942.3 949.2 955.5 14425.6 14684.3 14703.9 14728.9 14735.9 12675.8 12861.9 12928.1 12935.7 12946.2 752.5 767.2 883.5 891.2 903.5 913.4 914.5 923.5 923.8 924.0 957.8 968.9 970.2 970.5 971.1 783.5 798.4 930.8 952.8 960.9 710.9 712.8 713.7 714.3 714.4 13768.5 13967.6 13970.9 13971.8 13981.4 11994.8 12343.5 12388.8 12399.4 12623.6 10517.1 10591.8 10625.5 10645.6 10660.5 3854.2 3946.2 3952.1 3958.6 3958.6 49176.1 49723.8 49810.3 49817.0 49879.8 9741.1 9858.8 9860.1 9917.0 10000.1 18156.0 18174.5 18181.2 18203.5 19192.3 7606.8 7693.7 7705.4 7726.4 7767.9 4231.0 4248.5 4304.2 4329.7 4340.7 3242.6 3274.5 3274.9 3276.3 3278.8 585.1 595.5 601.0 609.1 610.1 658.3 662.4 677.9 711.3 721.1 8927.2 8958.0 8972.1 8973.1 9051.0 684.4 711.4 738.5 748.6 770.6 4015.8 4248.5 4273.3 4542.4 4638.7 58742.4 59983.7 59993.4 60184.0 60794.7 51079.6 51750.2 52469.7 52805.5 54481.2 48468.5 48901.0 49590.3 49657.0 50065.2 9270.2 9396.2 9447.1 9447.3 9524.2 35403.6 35494.3 36622.6 37841.0 37983.8 46783.0 47155.8 47306.8 47325.0 47668.0 11568.8 11594.2 11759.6 11783.3 12069.9 12296.3 12694.7 12936.7 12999.9 13125.5 11452.7 11522.1 11535.7 11936.0 12021.3 11570.1 11824.5 12028.4 12103.2 12680.4 10316.4 11544.5 11874.2 11965.9 12176.3 10277.2 10313.6 10481.8 10502.8 10539.2 9604.1 10189.2 10249.8 10382.0 10393.4 09:12:26 INFO - 2016-02-25 09:12:26,051 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:12:26 INFO - 2016-02-25 09:12:26,051 DEBUG : BROWSER_OUTPUT: 09:12:26 INFO - 2016-02-25 09:12:26,597 INFO : Browser exited with error code: 0 09:12:26 INFO - 2016-02-25 09:12:26,613 INFO : Completed test dromaeo_css (00:18:29) 09:12:26 INFO - 2016-02-25 09:12:26,613 INFO : Starting test kraken 09:12:26 INFO - 2016-02-25 09:12:26,614 DEBUG : operating with platform_type : linux_ 09:12:26 INFO - 2016-02-25 09:12:26,614 INFO : Initialising browser for kraken test... 09:12:26 INFO - 2016-02-25 09:12:26,620 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFJs86K/profile http://localhost:38292/getInfo.html 09:12:30 INFO - 2016-02-25 09:12:30,377 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:12:30 INFO - 2016-02-25 09:12:30,377 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:12:30 INFO - 2016-02-25 09:12:30,377 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:12:30 INFO - 2016-02-25 09:12:30,377 DEBUG : BROWSER_OUTPUT: __metrics 09:12:30 INFO - 2016-02-25 09:12:30,473 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:31 INFO - 2016-02-25 09:12:31,413 INFO : Browser initialized. 09:12:31 INFO - 2016-02-25 09:12:31,414 INFO : Running cycle 1/1 for kraken test... 09:12:31 INFO - 2016-02-25 09:12:31,414 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFJs86K/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:12:43 INFO - 2016-02-25 09:12:43,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 134811648 09:12:43 INFO - 2016-02-25 09:12:43,154 DEBUG : BROWSER_OUTPUT: 09:12:45 INFO - 2016-02-25 09:12:45,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38292/tests/kraken/driver.html?testName=audio-beat-detection) 09:12:46 INFO - 2016-02-25 09:12:46,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 164204544 09:12:46 INFO - 2016-02-25 09:12:46,148 DEBUG : BROWSER_OUTPUT: 09:12:48 INFO - 2016-02-25 09:12:48,725 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38292/tests/kraken/driver.html?testName=audio-dft) 09:12:49 INFO - 2016-02-25 09:12:49,287 DEBUG : BROWSER_OUTPUT: RSS: Main: 163364864 09:12:49 INFO - 2016-02-25 09:12:49,287 DEBUG : BROWSER_OUTPUT: 09:12:52 INFO - 2016-02-25 09:12:52,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38292/tests/kraken/driver.html?testName=audio-fft) 09:12:52 INFO - 2016-02-25 09:12:52,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 159842304 09:12:52 INFO - 2016-02-25 09:12:52,626 DEBUG : BROWSER_OUTPUT: 09:12:54 INFO - 2016-02-25 09:12:54,606 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38292/tests/kraken/driver.html?testName=audio-oscillator) 09:12:55 INFO - 2016-02-25 09:12:55,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 160436224 09:12:55 INFO - 2016-02-25 09:12:55,173 DEBUG : BROWSER_OUTPUT: 09:12:57 INFO - 2016-02-25 09:12:57,366 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38292/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:12:57 INFO - 2016-02-25 09:12:57,928 DEBUG : BROWSER_OUTPUT: RSS: Main: 158601216 09:12:57 INFO - 2016-02-25 09:12:57,928 DEBUG : BROWSER_OUTPUT: 09:13:00 INFO - 2016-02-25 09:13:00,752 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38292/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:01 INFO - 2016-02-25 09:13:01,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 158146560 09:13:01 INFO - 2016-02-25 09:13:01,319 DEBUG : BROWSER_OUTPUT: 09:13:04 INFO - 2016-02-25 09:13:04,606 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38292/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:05 INFO - 2016-02-25 09:13:05,179 DEBUG : BROWSER_OUTPUT: RSS: Main: 158937088 09:13:05 INFO - 2016-02-25 09:13:05,180 DEBUG : BROWSER_OUTPUT: 09:13:07 INFO - 2016-02-25 09:13:07,905 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38292/tests/kraken/driver.html?testName=json-parse-financial) 09:13:08 INFO - 2016-02-25 09:13:08,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 158339072 09:13:08 INFO - 2016-02-25 09:13:08,472 DEBUG : BROWSER_OUTPUT: 09:13:10 INFO - 2016-02-25 09:13:10,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38292/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:13:10 INFO - 2016-02-25 09:13:10,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 163745792 09:13:10 INFO - 2016-02-25 09:13:10,591 DEBUG : BROWSER_OUTPUT: 09:13:12 INFO - 2016-02-25 09:13:12,364 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:13:12 INFO - 2016-02-25 09:13:12,958 DEBUG : BROWSER_OUTPUT: RSS: Main: 160387072 09:13:12 INFO - 2016-02-25 09:13:12,958 DEBUG : BROWSER_OUTPUT: 09:13:14 INFO - 2016-02-25 09:13:14,936 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:13:15 INFO - 2016-02-25 09:13:15,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 166363136 09:13:15 INFO - 2016-02-25 09:13:15,503 DEBUG : BROWSER_OUTPUT: 09:13:17 INFO - 2016-02-25 09:13:17,870 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:13:18 INFO - 2016-02-25 09:13:18,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 171909120 09:13:18 INFO - 2016-02-25 09:13:18,451 DEBUG : BROWSER_OUTPUT: 09:13:20 INFO - 2016-02-25 09:13:20,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:13:21 INFO - 2016-02-25 09:13:21,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 171188224 09:13:21 INFO - 2016-02-25 09:13:21,539 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,148 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38292/tests/kraken/driver.html?testName=ai-astar) 09:13:23 INFO - 2016-02-25 09:13:23,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 228483072 09:13:23 INFO - 2016-02-25 09:13:23,394 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,397 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:13:23 INFO - 2016-02-25 09:13:23,397 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |0;ai-astar;106;109;103;108;109;108;98;108;108;108 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;136;141;159;124;148;150;123;166;122 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;160;159;160;159;156;162;161;161 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;71;71;156;70;70;72;71;165;71 09:13:23 INFO - 2016-02-25 09:13:23,398 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;135;92;91;92;132;94;92;141 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;108;109;109;109;109;109 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;144;146;144;145;146;146;146;145;145 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;94;95;96;96;95;96;95;96 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;56;58;60;57;57;58;55;58 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;66;66;65;66;66;66;66;66;67;67 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;77;78;77;77;78;78;78;86;77 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;112;112;121;125;113;117;119;113;120 09:13:23 INFO - 2016-02-25 09:13:23,399 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;153;145;133;147;142;144;146;150;134 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;66;64;67;63;65;65;62;64;64 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,885 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: __startTimestamp1456420403396__endTimestamp 09:13:23 INFO - 2016-02-25 09:13:23,400 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.50 Median:108.00 stddev:3.47 (3.2%) stddev-sans-first:3.68 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: Values: 106.0 109.0 103.0 108.0 109.0 108.0 98.0 108.0 108.0 108.0 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:149.00 stddev:15.52 (10.4%) stddev-sans-first:16.12 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: Values: 151.0 136.0 141.0 159.0 124.0 148.0 150.0 123.0 166.0 122.0 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:1.64 (1.0%) stddev-sans-first:1.73 09:13:23 INFO - 2016-02-25 09:13:23,404 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 160.0 159.0 160.0 159.0 156.0 162.0 161.0 161.0 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.70 Median:71.00 stddev:37.91 (53.4%) stddev-sans-first:39.60 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 156.0 70.0 70.0 72.0 71.0 165.0 71.0 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.60 Median:92.00 stddev:21.11 (22.9%) stddev-sans-first:21.97 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 135.0 92.0 91.0 92.0 132.0 94.0 92.0 141.0 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 108.0 109.0 109.0 109.0 109.0 109.0 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: Values: 146.0 144.0 146.0 144.0 145.0 146.0 146.0 146.0 145.0 145.0 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:13:23 INFO - 2016-02-25 09:13:23,405 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 94.0 95.0 96.0 96.0 95.0 96.0 95.0 96.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:1.34 (2.3%) stddev-sans-first:1.41 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 56.0 58.0 60.0 57.0 57.0 58.0 55.0 58.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 65.0 66.0 66.0 66.0 66.0 66.0 67.0 67.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:78.00 stddev:3.41 (4.4%) stddev-sans-first:2.88 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: Values: 85.0 77.0 78.0 77.0 77.0 78.0 78.0 78.0 86.0 77.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.70 Median:119.50 stddev:5.10 (4.3%) stddev-sans-first:4.68 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: Values: 125.0 112.0 112.0 121.0 125.0 113.0 117.0 119.0 113.0 120.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.90 Median:145.50 stddev:6.30 (4.3%) stddev-sans-first:6.67 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: Values: 145.0 153.0 145.0 133.0 147.0 142.0 144.0 146.0 150.0 134.0 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: 09:13:23 INFO - 2016-02-25 09:13:23,406 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.62 (2.5%) stddev-sans-first:1.51 09:13:23 INFO - 2016-02-25 09:13:23,407 DEBUG : BROWSER_OUTPUT: Values: 62.0 66.0 64.0 67.0 63.0 65.0 65.0 62.0 64.0 64.0 09:13:23 INFO - 2016-02-25 09:13:23,407 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:13:23 INFO - 2016-02-25 09:13:23,407 DEBUG : BROWSER_OUTPUT: 09:13:24 INFO - 2016-02-25 09:13:24,061 INFO : Browser exited with error code: 0 09:13:24 INFO - 2016-02-25 09:13:24,112 INFO : Completed test kraken (00:00:57) 09:13:24 INFO - 2016-02-25 09:13:24,112 INFO : Starting test v8_7 09:13:24 INFO - 2016-02-25 09:13:24,112 DEBUG : operating with platform_type : linux_ 09:13:24 INFO - 2016-02-25 09:13:24,112 INFO : Initialising browser for v8_7 test... 09:13:24 INFO - 2016-02-25 09:13:24,119 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpihUwqX/profile http://localhost:38292/getInfo.html 09:13:27 INFO - 2016-02-25 09:13:27,435 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:13:27 INFO - 2016-02-25 09:13:27,435 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:13:27 INFO - 2016-02-25 09:13:27,435 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:13:27 INFO - 2016-02-25 09:13:27,435 DEBUG : BROWSER_OUTPUT: __metrics 09:13:27 INFO - 2016-02-25 09:13:27,572 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:13:28 INFO - 2016-02-25 09:13:28,597 INFO : Browser initialized. 09:13:28 INFO - 2016-02-25 09:13:28,598 INFO : Running cycle 1/1 for v8_7 test... 09:13:28 INFO - 2016-02-25 09:13:28,598 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpihUwqX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:13:40 INFO - 2016-02-25 09:13:40,258 DEBUG : BROWSER_OUTPUT: RSS: Main: 134127616 09:13:40 INFO - 2016-02-25 09:13:40,258 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38292/tests/v8_7/run.html (next: http://localhost:38292/tests/v8_7/run.html) 09:14:01 INFO - 2016-02-25 09:14:01,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 425816064 09:14:01 INFO - 2016-02-25 09:14:01,852 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,852 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:14:01 INFO - 2016-02-25 09:14:01,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:14:01 INFO - 2016-02-25 09:14:01,852 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:14:01 INFO - 2016-02-25 09:14:01,853 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:14:01 INFO - 2016-02-25 09:14:01,853 DEBUG : BROWSER_OUTPUT: |0;Richards;165.20733520568314 09:14:01 INFO - 2016-02-25 09:14:01,853 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.88174982911826 09:14:01 INFO - 2016-02-25 09:14:01,853 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.700638711469 09:14:01 INFO - 2016-02-25 09:14:01,854 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5213.541666666667 09:14:01 INFO - 2016-02-25 09:14:01,854 DEBUG : BROWSER_OUTPUT: |4;RayTrace;973.7098344693281 09:14:01 INFO - 2016-02-25 09:14:01,854 DEBUG : BROWSER_OUTPUT: |5;Earley;539.3743257820928 09:14:01 INFO - 2016-02-25 09:14:01,854 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 09:14:01 INFO - 2016-02-25 09:14:01,855 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 09:14:01 INFO - 2016-02-25 09:14:01,855 DEBUG : BROWSER_OUTPUT: |8;Splay;522.256728778468 09:14:01 INFO - 2016-02-25 09:14:01,855 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 09:14:01 INFO - 2016-02-25 09:14:01,855 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: __startTimestamp1456420441849__endTimestamp 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,856 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.21 Median:165.21 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,857 DEBUG : BROWSER_OUTPUT: Values: 165.2 09:14:01 INFO - 2016-02-25 09:14:01,857 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,857 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.88 Median:170.88 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,857 DEBUG : BROWSER_OUTPUT: Values: 170.9 09:14:01 INFO - 2016-02-25 09:14:01,857 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,858 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.70 Median:277.70 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,858 DEBUG : BROWSER_OUTPUT: Values: 277.7 09:14:01 INFO - 2016-02-25 09:14:01,858 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,858 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5213.54 Median:5213.54 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,858 DEBUG : BROWSER_OUTPUT: Values: 5213.5 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:973.71 Median:973.71 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: Values: 973.7 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.37 Median:539.37 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,859 DEBUG : BROWSER_OUTPUT: Values: 539.4 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: Values: 10547.4 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,860 DEBUG : BROWSER_OUTPUT: Values: 23904.8 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:522.26 Median:522.26 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: Values: 522.3 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: Values: 4826.9 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:14:01 INFO - 2016-02-25 09:14:01,861 DEBUG : BROWSER_OUTPUT: 09:14:02 INFO - 2016-02-25 09:14:02,461 INFO : Browser exited with error code: 0 09:14:02 INFO - 2016-02-25 09:14:02,476 INFO : Completed test v8_7 (00:00:38) 09:14:02 INFO - 2016-02-25 09:14:02,916 INFO : Completed test suite (00:20:05) 09:14:02 INFO - 2016-02-25 09:14:02,917 DEBUG : Working with test: dromaeo_css 09:14:02 INFO - 2016-02-25 09:14:02,917 DEBUG : Generating results file: dromaeo_css 09:14:02 INFO - 2016-02-25 09:14:02,918 DEBUG : Working with test: kraken 09:14:02 INFO - 2016-02-25 09:14:02,918 DEBUG : Generating results file: kraken 09:14:02 INFO - 2016-02-25 09:14:02,918 INFO : javascript benchmark 09:14:02 INFO - 2016-02-25 09:14:02,918 DEBUG : Working with test: v8_7 09:14:02 INFO - 2016-02-25 09:14:02,918 DEBUG : Generating results file: v8_7 09:14:02 INFO - 2016-02-25 09:14:02,919 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:14:02 INFO - 2016-02-25 09:14:02,979 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:14:02 INFO - 2016-02-25 09:14:02,979 DEBUG : process_Request line: dromaeo_css 7283.60 graph.html#tests=[[72,53,35]] 09:14:02 INFO - 2016-02-25 09:14:02,980 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:14:03 INFO - 2016-02-25 09:14:03,026 DEBUG : process_Request line: kraken 1480.30 graph.html#tests=[[232,53,35]] 09:14:03 INFO - 2016-02-25 09:14:03,026 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:14:03 INFO - 2016-02-25 09:14:03,061 DEBUG : process_Request line: v8_7 23221.52 graph.html#tests=[[230,53,35]] 09:14:03 INFO - 2016-02-25 09:14:03,062 INFO : javascript benchmark 09:14:03 INFO - 2016-02-25 09:14:03,066 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3259.0, 3281.704646581807, 3282.8686852525902, 3288.4245257080006, 3302.2239773653196, 5358.223057656637, 5382.596305277105, 5395.91906121408, 5396.730163491819, 5404.7838086476495, 5400.432954539776, 5403.459654034605, 5406.216098665691, 5430.91853622195, 5434.510894019538, 4890.119778439879, 4957.4298955620025, 4962.6526143169995, 5003.374578177728, 5034.345535080434, 4910.828121015765, 4920.8031678732805, 4966.826161084363, 5011.799528018875, 5024.196128619422, 5027.899442011158, 5038.345015148044, 5046.940142570064, 5047.570956468705, 5058.190689489682, 4866.902661946741, 4979.0041991601465, 4991.301217829507, 5026.47222041684, 5047.369078865142, 4856.2958371036, 4877.829275975359, 4954.281629163787, 4977.676451030671, 4978.0, 5233.162693968985, 5255.448177941337, 5272.578193744491, 5276.630635855492, 5287.603429742785, 5704.914426283609, 5802.535797136219, 5804.0, 5806.071028635398, 5822.563307751935, 5786.566007549451, 5878.441548052971, 5884.705764711747, 5904.350521442638, 5935.6438613682885, 5520.641158324696, 5570.637908535932, 5572.7770889164385, 5581.944180558224, 5582.134769110754, 5652.943470565324, 5812.38969908162, 5890.705464726832, 6010.519158467312, 6020.548458865515, 5839.124131380327, 5849.736761845727, 5852.970735146297, 5855.063189889597, 5867.794627188028, 5946.375630558816, 5953.880922381528, 5954.46409823118, 5956.880862382728, 5966.492848107872, 5673.177389278531, 5686.715664216773, 5796.101604251347, 5796.681182535001, 5891.646501209942, 5829.795957141565, 5838.416158384128, 5866.794662186803, 5871.119332100219, 5872.677002764804, 4565.0, 4576.107659006529, 4578.244589642738, 4578.2903649934315, 4597.678162528591, 3997.9400308995387, 4020.7788571628257, 4076.3885417187657, 4098.569650186688, 4105.220008198433, 5457.836264912059, 5522.337319521603, 5526.0, 5529.447055294519, 5557.972210139004, 5748.39641837601, 5851.912221316684, 5877.0, 5877.059670452707, 5877.882442351129, 5346.358436987586, 5397.892042159214, 5420.430854760272, 5437.293151890308, 5473.945260547343, 5052.747362631853, 5137.537621614073, 5138.409082955508, 5140.614453915897, 5179.223116532475, 5849.034909239927, 5964.940350596438, 6091.604045737013, 6097.085437184369, 6103.938960610337, 5665.631733937281, 5751.568632352674, 5757.309122905278, 5757.79847705345, 5759.366469688247, 6074.058520929263, 6145.569810113249, 6146.078088286793, 6147.815565532862, 6150.938490615215, 5628.690422026664, 5637.802676906289, 5742.196092546963, 5810.128480727924, 5835.358110607915, 5791.449812267828, 5805.506531944663, 5830.708464576839, 5832.270966129234, 5898.085796701518, 5668.971655141698, 5683.602147849776, 5685.0, 5686.033374326457, 5691.544676425793, 5566.4990150886615, 5636.830895073154, 5660.462256085665, 5708.200851880823, 5811.4188581143235, 5579.972100139474, 5607.074832652566, 5618.213450116905, 5619.8876022480135, 5627.296587926509, 5704.771809127755, 5722.62802917809, 5723.828285151452, 5727.36998930109, 5728.770849166153, 5505.6696598204235, 5509.393966663583, 5568.972155139199, 5591.8881622368135, 5607.74765135578], "unit": "ms", "name": "dojo.html", "value": 5388.074398661706}, {"lowerIsBetter": false, "replicates": [551.3218740948633, 605.2132228103466, 607.6414915200031, 611.2176414189838, 611.6269075863726, 32838.507422388655, 41058.0, 41372.17255654885, 41526.584734152646, 41546.37680434792, 45317.32024019642, 45625.54374456263, 45876.54123458765, 45891.31163032549, 45909.77045114778, 8138.470999385021, 9117.0, 9294.814103717921, 9297.860532092023, 9303.674371397003, 8224.83550328993, 10937.726556836104, 11163.441827908613, 11167.609133680322, 11179.385133817637, 10852.511636976316, 10979.121670266359, 10982.231243812936, 10984.725381865437, 10986.340819550853, 10779.514921828497, 10837.67486975384, 10854.45727713611, 10855.620053298175, 10870.076043536308, 10493.160547156209, 10626.840597391045, 10635.308704934154, 10635.680929572125, 10638.361698298128, 42194.78902605467, 42596.0, 42618.57381426177, 42651.0, 42684.78657606692, 3717.3308804415196, 3759.3984962405884, 3771.9434208486896, 4354.564543545652, 4375.234333991571, 32337.51493727596, 32385.676143238503, 32503.51244731331, 32570.02289931306, 33691.15772105692, 4280.978595107035, 4332.91334173318, 4348.9782551087355, 4390.297552391602, 4458.777061146962, 286239.0, 287806.0, 288025.0, 288337.0, 401163.99418003013, 50701.49298506968, 61064.0, 62340.37659623437, 62552.99999999909, 62641.0, 55832.72083639556, 56371.436285637436, 56389.43610563924, 56390.026349078405, 56396.0, 16134.435294764628, 16373.345066197218, 16394.83605163933, 16399.508014759576, 16407.343706251853, 12399.628011159679, 12622.684432889251, 12645.304508251951, 12659.050571207197, 13012.544560940321, 19855.205791768167, 20123.899380503295, 20165.49586260326, 20178.19287228524, 20222.898885505772, 7775.105862825847, 7952.409518096404, 8052.611326826894, 8077.798055048671, 8144.918550814535, 1017.9210037360422, 1072.7747173093737, 1094.7482079121846, 1112.6105862948066, 1164.4119719541577, 29590.852045739633, 30077.398452031273, 30138.0, 30514.389712205633, 30687.693123068482, 1136.5567428702811, 1140.9365852685567, 1165.2891736041008, 1175.506287359311, 1232.9951089861734, 13752.65618359549, 15340.539783806504, 15518.689626207413, 15556.222188890737, 16012.759808602881, 333692.0, 339754.0, 341184.0, 341342.0, 342227.0, 56225.718871405385, 56596.15105773417, 56625.43374566284, 56640.0, 56749.71625141848, 1407.5917983784793, 1410.8659677330638, 1462.5612316305278, 1496.8203815542204, 1559.50095969291, 27730.0, 28772.42455150927, 29268.853655731586, 29278.70721292845, 29421.0, 53035.0, 53181.46818531765, 53808.0, 53996.46003539914, 54247.0, 261947.0, 262903.0, 264474.0, 264737.0, 265140.0, 33732.83133584316, 33800.0, 33807.15482112967, 33887.66112338944, 34130.82934585411, 46543.767281163375, 46599.30101048419, 46710.0, 46837.76581117073, 47356.763216183695, 32879.83560082184, 32972.67027329696, 34211.48682769711, 34715.826420867736, 34897.82551087228, 34022.48966265458, 34074.659253407146, 34695.13262168466, 37248.44127338038, 37494.0, 36558.8172059138, 36798.71204507937, 37167.25665486729, 37346.813265933495, 37723.81138094292, 31377.52933706042, 33427.0, 34377.0, 34511.30977380489, 34516.0, 34450.65549344474, 35142.12144696403, 36713.632863671024, 36743.26513469662, 36745.816270918476], "unit": "ms", "name": "ext.html", "value": 19961.10579147614}, {"lowerIsBetter": false, "replicates": [373.65250317205, 374.34115955917594, 377.6525596451265, 380.06314434917937, 381.36883457877224, 14962.17708026058, 17056.91471542642, 17382.913085434586, 18996.33512827051, 19053.333133340337, 17111.486655400357, 17248.0, 17412.47762567125, 17432.38986635465, 17457.1271436428, 4307.418498502709, 5543.085390910495, 5653.321601407837, 5672.631278966875, 5673.26247587813, 4400.383946247528, 6095.116208149838, 6190.3500132486115, 6191.473724733403, 6207.875842483148, 5491.972540137313, 5622.943770562303, 6103.084537319393, 6112.6638034908065, 6127.81616551502, 5565.6104072714925, 5567.081431563787, 5569.749361278752, 5587.05020146574, 5588.804391846266, 5395.460453954613, 5417.052015897202, 5417.45825417459, 5513.751881165357, 5522.364928033247, 13943.511977080754, 14061.367238474293, 14078.2960851957, 14113.294335283297, 14266.0, 2445.572024895654, 2487.4154573675173, 2487.5522405966917, 2520.231329444519, 2539.2509209783157, 13379.130356526892, 13573.728525429535, 13940.512082077079, 14049.367778449994, 14295.78556321656, 1916.48254971157, 1916.6933290673564, 1929.5754933914516, 1934.1586409911733, 1943.319838056683, 62326.68836655788, 66154.0, 66338.0, 67440.32559674438, 68342.3165768341, 10915.890841091488, 11131.72170695739, 11139.665810025712, 11183.72040698989, 11249.381284029458, 14829.480968166063, 14889.70220595582, 14892.255387230594, 14905.478308259158, 14921.850781492263, 6842.657867106725, 7125.92874071263, 7149.213586505481, 7543.283388078124, 7586.734464293833, 7069.823254418578, 7275.781726548212, 7513.0, 7548.018757561482, 7568.318851303409, 4430.933535996963, 4557.589816916494, 4639.373684552542, 4660.603848672833, 4874.780634871439, 1858.823411775879, 1887.5186827358937, 1904.0955546115483, 1920.9423717288503, 1952.0434986856621, 961.3510880155867, 973.454865275448, 992.9205663546899, 993.6720882108887, 1033.715728174758, 14915.627109322137, 17033.7444938326, 17048.57378565546, 17168.22742976569, 17542.736858947126, 1017.0439786600572, 1017.2167431078028, 1031.1235449867702, 1105.8617672790901, 1119.1270808769173, 9784.657536986171, 10101.747456313651, 10109.140723038625, 10127.949360253151, 10142.239332050125, 575302.0, 575407.0, 589871.0, 592445.0, 597234.0, 79208.60395697984, 80821.59589202017, 82008.58995704983, 82494.58752706199, 84335.57832210923, 1194.1879521925, 1196.108898870347, 1196.108898870347, 1201.2672269915345, 1207.5592408770735, 15508.0, 15949.0, 16012.0, 16045.518634440985, 16140.838591613934, 34090.65909340875, 34240.315193696486, 34300.828495857364, 34897.30205395928, 37550.43674344832, 46403.30395044145, 46600.533994660975, 46687.53312466832, 46873.296900547204, 47331.29003064888, 10342.793144137226, 10403.635872744419, 10413.427261500387, 10584.89415105839, 10607.204459665401, 14862.925685371503, 14901.552953411416, 16550.50348489547, 17627.55931101733, 18036.0, 14318.57044288673, 14919.701605967603, 14977.475788347356, 15037.097774133586, 15490.690186196438, 14587.635309116933, 14788.334524946187, 14876.553703388916, 15129.167895765839, 15220.0, 15302.693946121239, 15437.536873893801, 15665.843341566439, 15715.921420392824, 15746.685066298838, 9962.950185249027, 10148.0, 10490.685279441628, 10774.892251077703, 10788.622398216025, 12131.939340303243, 15162.620934476727, 15209.6197595061, 15679.902406831869, 15923.920380398487], "unit": "ms", "name": "jquery.html", "value": 9310.639912509227}, {"lowerIsBetter": false, "replicates": [1558.9220538973057, 1561.7501199808025, 1764.6206065695874, 1803.5851754096557, 1847.076461769115, 7544.547327160368, 7892.842143157133, 7894.250046245605, 7897.40769442292, 7912.169222231655, 6893.620850853201, 7022.36798688118, 7250.057492525977, 7389.261073892608, 7793.688252469909, 2325.244295603927, 2341.496578235679, 2365.7161140663143, 2365.7279412867483, 2366.018102487474, 2039.1945181653239, 2331.160782118436, 2363.7518060603647, 2374.548835721216, 2375.928722138339, 2049.3646969439446, 2304.758000409958, 2344.800691941187, 2347.084636991575, 2347.1315613223046, 2227.9108835646553, 2234.977650223493, 2236.7763223677666, 2299.2297580310615, 2300.953980920372, 2279.361778701966, 2299.1378233162563, 2323.9767602323927, 2324.8837558122027, 2325.081592770863, 7670.386369090459, 7769.689212431553, 7785.610719464004, 7789.922100779033, 7791.065072191317, 4720.504347043546, 4799.328094066834, 4813.735244561548, 4814.157522433595, 4814.590759785422, 3083.814971101741, 3104.1153271317567, 3105.4254962831724, 3130.98434507826, 3251.349730053999, 1097.5609756097545, 1122.3770807202, 1130.0507573638183, 2105.0948092320295, 2123.3205374280237, 10575.20685948557, 10688.51901664427, 10707.357558546435, 10715.142788576895, 10719.088877445427, 4123.7216462896595, 4349.586789255016, 4430.446194225722, 4462.598366146997, 4469.10617876426, 8661.870071948926, 8725.476471411735, 8779.956100219459, 8796.34027447944, 8810.0, 4847.224444088929, 4854.587360074363, 4855.902881942341, 4856.902861942742, 4858.46556878743, 4406.691531592822, 4459.019015816515, 4466.196084704719, 4466.665000125004, 4496.033352829092, 3534.6111927687934, 3541.8229088545468, 3553.697935675445, 3573.2138929436, 3576.034470692898, 1029.3875144289136, 1052.742078190833, 1078.4823284823328, 1083.2254937719567, 1088.0479580367178, 1122.6351435783404, 1132.6375559820942, 1179.021412227866, 1360.5170164591427, 1425.62933637253, 7263.164736055315, 7387.076615423072, 7455.0, 7465.589392583352, 7492.288232617892, 654.8395643067383, 663.4824836627438, 665.4609766089386, 670.8658268346351, 810.1979040749733, 5726.284214473191, 5739.225204597409, 5747.942520574825, 5756.913646295309, 5760.596758226968, 9053.864192037125, 9113.863292050624, 9202.723918282461, 9387.0, 9389.0, 9232.446053236827, 9375.34372593912, 9394.718158455258, 9399.436033837992, 9403.106704863027, 7104.68028938699, 7270.272972702687, 7298.124225093023, 7311.597862117635, 7380.520266182681, 7286.817829554304, 7293.088363954505, 7329.230430804689, 7330.230325815713, 7334.926650733424, 8150.592470376576, 8322.417430779788, 8455.74632761018, 8480.872786908078, 8481.957590212009, 8426.283765879969, 8560.614772335135, 8566.314694824274, 8638.308935285288, 8679.479231246145, 6764.966175169093, 6786.796396108125, 6855.143107111611, 6879.6560171992205, 6880.174379074543, 7446.851062978819, 7450.850982980419, 7481.177070522347, 7490.0, 7494.962525187558, 6068.393160683896, 6995.860082798417, 7232.63836808168, 7313.414926805949, 7382.114146302478, 7087.929120708934, 7110.502264841618, 7139.714411423485, 7183.712651494091, 7209.423246140401, 7278.854422911618, 7311.817204569928, 7314.122305323182, 7314.926850731425, 7317.341439270491, 6615.834604134935, 6629.071929929717, 6648.368405001517, 6656.866862662817, 6669.833254168685, 6418.229812422539, 6458.0, 6461.935380646321, 6469.676516174267, 6480.967595161994], "unit": "ms", "name": "mootools.html", "value": 4511.824100029967}, {"lowerIsBetter": false, "replicates": [347.49786558423074, 360.70782666040526, 361.96199399063096, 362.5160410851513, 371.8159511042033, 6133.049377346507, 6168.167297414848, 6178.814635560935, 6202.914489964255, 6221.564490485668, 5806.796762113327, 5934.851628709273, 5988.041913293874, 6065.575409721321, 6068.39316068393, 4746.813296675831, 4748.643851711118, 4756.690815097017, 4758.072175925696, 4781.426228852542, 4562.0, 4766.833160839371, 4773.856784296477, 4775.641826862981, 4786.138495070903, 4611.216093264136, 4622.07558488302, 4719.528047195287, 4727.598154156901, 4730.574248317651, 4684.063187362541, 4716.2454007359, 4721.126591580569, 4721.929171062454, 4722.693024953367, 4207.747535147901, 4209.221294060609, 4209.326507758744, 4215.91568168638, 4217.219814334328, 5743.310802703661, 5797.768089276424, 5812.709364531756, 5814.156947242626, 5827.213326200951, 5515.448455154493, 5517.227588137664, 5521.834344969658, 5528.336599608073, 5531.9723401382735, 6405.93594064058, 6412.903806442907, 6415.518836087265, 6417.711202995876, 6427.485801135945, 4663.300504924255, 4668.929966050512, 4669.136209801198, 4671.0, 4674.906501869978, 6529.804105876831, 6686.331366863324, 6687.230968438595, 6688.598684078971, 6695.196576410814, 4776.14029474691, 4778.259369797687, 4779.593734532604, 4781.163296423112, 4782.617390608743, 4848.369711937426, 4850.345203397496, 4851.708897466093, 4852.4662287148385, 4855.441624213261, 5416.91874621881, 5482.588805839578, 5487.368952570426, 5489.1217405215775, 5493.945060549343, 6449.516286278548, 6485.805425837232, 6489.643069631215, 6501.44737697301, 6501.609903405811, 1667.483080245128, 1686.6289416328389, 1690.0, 1690.5351028467269, 1690.7971043474615, 1142.4459137318395, 1149.844770955927, 1164.016406136813, 1171.8183681529376, 1182.698411904976, 6280.874382512324, 6286.0, 6294.464970477469, 6295.118683384329, 6295.527835412362, 6510.18622672166, 6702.731890724414, 6718.32816718334, 6720.126383570202, 6727.529072964943, 6189.721462534197, 6221.688915554204, 6341.524385671093, 6347.809565713036, 6351.333110023381, 3564.3049605327233, 3644.945325820062, 3645.434957581579, 3645.8359373828243, 3646.9817650912105, 6787.355201255873, 6927.3419025192525, 6927.445804335742, 6927.58434493932, 6934.618595977169, 6916.827079322957, 6924.826879327957, 6925.480588955848, 6927.653617319113, 6930.861382772317, 6965.303469653096, 7004.054452648929, 7004.089468369079, 7011.298870112948, 7015.158181018311, 6665.600063996176, 6758.0, 6767.89848152288, 6768.763093291711, 6769.627670477974, 6611.966940165268, 6615.338466153346, 6643.966780166068, 6682.465402767864, 6684.4652427804685, 6896.586204827727, 6922.653867306715, 6954.756583519553, 6993.4055605274125, 6998.090248267693, 4505.7296562206375, 4829.541193586604, 4832.830850920342, 4833.3958255218095, 4835.2505361669, 6694.966525167343, 6743.325667433218, 6746.595204287562, 6748.392644662004, 6749.865002700017, 6560.0, 6561.934380656132, 6564.048213009182, 6564.606123632598, 6567.573107747981, 6414.93585064162, 6528.934710652833, 6546.574472659262, 6549.901751473827, 6554.180727409074, 6360.4911607072245, 6411.5832470889245, 6413.4869210463985, 6413.967930160319, 6421.293657697743, 6546.509011824037, 6547.803565893031, 6549.770758023446, 6550.213974323112, 6552.0, 6306.1801965746, 6405.839854003687, 6409.9038514423255, 6414.871702566016, 6423.871522569429], "unit": "ms", "name": "prototype.html", "value": 4956.332310629324}, {"lowerIsBetter": false, "replicates": [934.5560858592165, 941.4492521874703, 942.2627267787079, 949.1789601994275, 955.5270141280067, 14425.567232983014, 14684.339204735787, 14703.852961470395, 14728.926355368209, 14735.852641473595, 12675.809862852064, 12861.871381286184, 12928.09503334767, 12935.676608084803, 12946.223226606411, 752.5259086775324, 767.2059418501851, 883.5493898111961, 891.1934699097295, 903.4850135422812, 913.4108500017485, 914.5335878701843, 923.5059243304834, 923.8152369526088, 923.9815203695922, 957.7509847439646, 968.9467079310635, 970.2141265574871, 970.5389939778584, 971.0677749360588, 783.4946459533597, 798.4211446701124, 930.7580029192425, 952.7713348796308, 960.9039096090335, 710.9217986021533, 712.7647876200841, 713.6859781696037, 714.253604982789, 714.4177495341332, 13768.518101866486, 13967.580972570839, 13970.860291397057, 13971.79042314366, 13981.370838312301, 11994.760104797942, 12343.455351500903, 12388.81416778749, 12399.380030998414, 12623.621291361182, 10517.05346518817, 10591.810445586163, 10625.521851516623, 10645.627403040933, 10660.520276587573, 3854.1520865409566, 3946.2304850554156, 3952.071263253133, 3958.604139586047, 3958.604139586047, 49176.067214122464, 49723.75138124322, 49810.25284620734, 49817.0, 49879.75060124713, 9741.123298903132, 9858.802823943624, 9860.112589866947, 9917.0, 10000.099991000845, 18156.0, 18174.5456363592, 18181.18184681666, 18203.544911377056, 19192.328268510537, 7606.8098297543, 7693.692252309845, 7705.4220933430215, 7726.38188944883, 7767.92232077672, 4231.048014196781, 4248.4901811782165, 4304.203722311382, 4329.653627709776, 4340.739555626609, 3242.578464799561, 3274.5088236764677, 3274.9345013099605, 3276.3283526876935, 3278.8032718036516, 585.1018686316569, 595.4849055566904, 600.9699515024231, 609.1228630771675, 610.1305639463747, 658.26932105363, 662.3707477895922, 677.8745932002497, 711.2922641971272, 721.0878239027634, 8927.151920567536, 8958.0, 8972.147645973622, 8973.147550982647, 9050.954745226363, 684.3772167327808, 711.3668834737089, 738.4978214813979, 748.554610007045, 770.622395026433, 4015.759054456742, 4248.532661407243, 4273.273543497613, 4542.409486766699, 4638.652101092432, 58742.41257587455, 59983.700081499315, 59993.40006599965, 60184.0, 60794.69602651958, 51079.59588706368, 51750.22374664383, 52469.7376513115, 52805.47194528006, 54481.1827822583, 48468.51531484711, 48901.0, 49590.25614615784, 49657.0, 50065.24902126471, 9270.212031977357, 9396.154346108882, 9447.102525260088, 9447.291453140904, 9524.190443812076, 35403.64596354003, 35494.29011419809, 36622.633773661924, 37841.0, 37983.81008094942, 46783.0, 47155.76422118005, 47306.76346618245, 47325.0, 47668.0, 11568.768624627292, 11594.246373985665, 11759.647210583697, 11783.293002419883, 12069.879301207227, 12296.262224266573, 12694.746105078031, 12936.676583085498, 12999.935000324938, 13125.474981000652, 11452.713682158013, 11522.135839812216, 11535.653930382103, 11936.0, 12021.278723276631, 11570.132240081859, 11824.46789894474, 12028.398580071136, 12103.213291136048, 12680.365981701063, 10316.43259620728, 11544.480498377761, 11874.168808183344, 11965.880341196476, 12176.269423834598, 10277.177825773771, 10313.639022634172, 10481.790364192826, 10502.842457362993, 10539.156867450432, 9604.135627793532, 10189.235807314333, 10249.846252306072, 10382.0, 10393.428361440194], "unit": "ms", "name": "yui.html", "value": 6666.934381444884}], "name": "dromaeo_css", "value": 7283.600570572538}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [106.0, 109.0, 103.0, 108.0, 109.0, 108.0, 98.0, 108.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 106.5}, {"lowerIsBetter": true, "replicates": [151.0, 136.0, 141.0, 159.0, 124.0, 148.0, 150.0, 123.0, 166.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 160.0, 159.0, 160.0, 159.0, 156.0, 162.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 159.7}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 156.0, 70.0, 70.0, 72.0, 71.0, 165.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.7}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 135.0, 92.0, 91.0, 92.0, 132.0, 94.0, 92.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 105.6}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.9}, {"lowerIsBetter": true, "replicates": [146.0, 144.0, 146.0, 144.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 56.0, 58.0, 60.0, 57.0, 57.0, 58.0, 55.0, 58.0], "unit": "ms", "name": "json-parse-financial", "value": 57.3}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 66.1}, {"lowerIsBetter": true, "replicates": [85.0, 77.0, 78.0, 77.0, 77.0, 78.0, 78.0, 78.0, 86.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.1}, {"lowerIsBetter": true, "replicates": [125.0, 112.0, 112.0, 121.0, 125.0, 113.0, 117.0, 119.0, 113.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.7}, {"lowerIsBetter": true, "replicates": [145.0, 153.0, 145.0, 133.0, 147.0, 142.0, 144.0, 146.0, 150.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.9}, {"lowerIsBetter": true, "replicates": [62.0, 66.0, 64.0, 67.0, 63.0, 65.0, 65.0, 62.0, 64.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1480.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.20733520568314], "unit": "score", "name": "Richards", "value": 213.68300599999995}, {"lowerIsBetter": false, "replicates": [170.88174982911826], "unit": "score", "name": "DeltaBlue", "value": 386.92253599999987}, {"lowerIsBetter": false, "replicates": [277.700638711469], "unit": "score", "name": "Encrypt", "value": 958.5177809999998}, {"lowerIsBetter": false, "replicates": [5213.541666666667], "unit": "score", "name": "Decrypt", "value": 51.05569630369634}, {"lowerIsBetter": false, "replicates": [973.7098344693281], "unit": "score", "name": "RayTrace", "value": 759.9687029999998}, {"lowerIsBetter": false, "replicates": [539.3743257820928], "unit": "score", "name": "Earley", "value": 1235.6224020000004}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [522.256728778468], "unit": "score", "name": "Splay", "value": 156.03628543111998}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23221.52204001349}]} 09:14:03 INFO - RETURN: dromaeo_css: 7283.60 09:14:03 INFO - RETURN: kraken: 1480.30 09:14:03 INFO - RETURN: v8_7: 23221.52 09:14:03 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23221.52"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1480.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7283.60"}}} 09:14:03 INFO - Return code: 0 09:14:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:14:03 INFO - # TBPL SUCCESS # 09:14:03 INFO - Running post-action listener: _resource_record_post_action 09:14:03 INFO - Running post-run listener: _resource_record_post_run 09:14:04 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 327680; Write bytes: 506195968; Read time: 420; Write time: 512072 09:14:04 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 242192384; Read time: 120; Write time: 310796 09:14:04 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 323584; Write bytes: 263733248; Read time: 300; Write time: 201216 09:14:04 INFO - Running post-run listener: _upload_blobber_files 09:14:04 INFO - Blob upload gear active. 09:14:04 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:14:04 INFO - Copying logs to upload dir... 09:14:04 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.918745 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-25 09:14:03.901453) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:14:03.905157) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009989 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410566/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:14:03.957430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:14:03.957836) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1456418455.765632-1587288190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:14:04.007554) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:14:04.007900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:14:04.008225) ========= ========= Total master_lag: 0.33 =========