builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1453916882.32 results: success (0) buildid: 20160127073601 builduid: 96d918783cdd46c5a11a02d1d93a6ade revision: 07bd7f4a0a1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.316453) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.316912) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.317205) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006452 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.343628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.344195) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.365823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.366207) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.423237) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.423829) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.424439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.424884) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-27 09:48:02-- 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% 82.1M=0s 2016-01-27 09:48:02 (82.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097243 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.578610) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.581782) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017614 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:02.616483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-27 09:48:02.616841) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 07bd7f4a0a1b --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 07bd7f4a0a1b --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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-27 09:48:02,897 Setting DEBUG logging. 2016-01-27 09:48:02,897 attempt 1/10 2016-01-27 09:48:02,897 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/07bd7f4a0a1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-27 09:48:03,605 unpacking tar archive at: mozilla-beta-07bd7f4a0a1b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.994373 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-27 09:48:03.629635) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:03.630329) ========= echo 07bd7f4a0a1b in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'07bd7f4a0a1b'] 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07bd7f4a0a1b program finished with exit code 0 elapsedTime=0.004695 script_repo_revision: '07bd7f4a0a1b' ========= master_lag: 0.06 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:03.691719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:03.692322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 09:48:03.708135) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-27 09:48:03.708735) ========= /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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:04 INFO - MultiFileLogger online at 20160127 09:48:04 in /builds/slave/test-pgo 09:48:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:48:04 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:48:04 INFO - {'append_to_log': False, 09:48:04 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:48:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:48:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:48:04 INFO - 'branch': 'Mozilla-Beta', 09:48:04 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:04 INFO - 'config_files': ('talos/linux_config.py',), 09:48:04 INFO - 'default_actions': ('clobber', 09:48:04 INFO - 'read-buildbot-config', 09:48:04 INFO - 'download-and-extract', 09:48:04 INFO - 'populate-webroot', 09:48:04 INFO - 'create-virtualenv', 09:48:04 INFO - 'install', 09:48:04 INFO - 'run-tests'), 09:48:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:04 INFO - 'download_minidump_stackwalk': True, 09:48:04 INFO - 'download_symbols': 'ondemand', 09:48:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:04 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:04 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:04 INFO - 'installer_path': 'installer.exe', 09:48:04 INFO - 'log_level': 'info', 09:48:04 INFO - 'log_name': 'talos', 09:48:04 INFO - 'log_to_console': True, 09:48:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:04 INFO - 'opt_config_files': (), 09:48:04 INFO - 'pip_index': False, 09:48:04 INFO - 'sps_profile': False, 09:48:04 INFO - 'sps_profile_interval': 0, 09:48:04 INFO - 'suite': 'dromaeojs', 09:48:04 INFO - 'system_bits': '32', 09:48:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:04 INFO - 'title': 'talos-linux64-ix-037', 09:48:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:04 INFO - 'use_talos_json': True, 09:48:04 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:48:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:04 INFO - 'work_dir': 'build'} 09:48:04 INFO - ##### 09:48:04 INFO - ##### Running clobber step. 09:48:04 INFO - ##### 09:48:04 INFO - Running pre-action listener: _resource_record_pre_action 09:48:04 INFO - Running main action method: clobber 09:48:04 INFO - rmtree: /builds/slave/test-pgo/build 09:48:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:48:05 INFO - Running post-action listener: _resource_record_post_action 09:48:05 INFO - ##### 09:48:05 INFO - ##### Running read-buildbot-config step. 09:48:05 INFO - ##### 09:48:05 INFO - Running pre-action listener: _resource_record_pre_action 09:48:05 INFO - Running main action method: read_buildbot_config 09:48:05 INFO - Using buildbot properties: 09:48:05 INFO - { 09:48:05 INFO - "properties": { 09:48:05 INFO - "buildnumber": 90, 09:48:05 INFO - "product": "firefox", 09:48:05 INFO - "script_repo_revision": "production", 09:48:05 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:48:05 INFO - "repository": "", 09:48:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:48:05 INFO - "buildid": "20160127073601", 09:48:05 INFO - "slavename": "talos-linux64-ix-037", 09:48:05 INFO - "pgo_build": "True", 09:48:05 INFO - "basedir": "/builds/slave/test-pgo", 09:48:05 INFO - "project": "", 09:48:05 INFO - "platform": "ubuntu64_hw", 09:48:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:48:05 INFO - "slavebuilddir": "test", 09:48:05 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:48:05 INFO - "branch": "mozilla-beta", 09:48:05 INFO - "repo_path": "releases/mozilla-beta", 09:48:05 INFO - "stage_platform": "linux64-pgo", 09:48:05 INFO - "builduid": "96d918783cdd46c5a11a02d1d93a6ade", 09:48:05 INFO - "revision": "07bd7f4a0a1b" 09:48:05 INFO - }, 09:48:05 INFO - "sourcestamp": { 09:48:05 INFO - "repository": "", 09:48:05 INFO - "hasPatch": false, 09:48:05 INFO - "project": "", 09:48:05 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:48:05 INFO - "changes": [ 09:48:05 INFO - { 09:48:05 INFO - "category": null, 09:48:05 INFO - "files": [ 09:48:05 INFO - { 09:48:05 INFO - "url": null, 09:48:05 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:48:05 INFO - } 09:48:05 INFO - ], 09:48:05 INFO - "repository": "", 09:48:05 INFO - "rev": "07bd7f4a0a1b", 09:48:05 INFO - "who": "sendchange", 09:48:05 INFO - "when": 1453916819, 09:48:05 INFO - "number": 7525424, 09:48:05 INFO - "comments": "Bug 1167179 - Disable test_ssl_status_after_restart.py on Windows XP again due to test failures. r=maja_zf, a=test-only", 09:48:05 INFO - "project": "", 09:48:05 INFO - "at": "Wed 27 Jan 2016 09:46:59", 09:48:05 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:48:05 INFO - "revlink": "", 09:48:05 INFO - "properties": [ 09:48:05 INFO - [ 09:48:05 INFO - "buildid", 09:48:05 INFO - "20160127073601", 09:48:05 INFO - "Change" 09:48:05 INFO - ], 09:48:05 INFO - [ 09:48:05 INFO - "builduid", 09:48:05 INFO - "96d918783cdd46c5a11a02d1d93a6ade", 09:48:05 INFO - "Change" 09:48:05 INFO - ], 09:48:05 INFO - [ 09:48:05 INFO - "pgo_build", 09:48:05 INFO - "True", 09:48:05 INFO - "Change" 09:48:05 INFO - ] 09:48:05 INFO - ], 09:48:05 INFO - "revision": "07bd7f4a0a1b" 09:48:05 INFO - } 09:48:05 INFO - ], 09:48:05 INFO - "revision": "07bd7f4a0a1b" 09:48:05 INFO - } 09:48:05 INFO - } 09:48:05 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:48:05 INFO - Running post-action listener: _resource_record_post_action 09:48:05 INFO - ##### 09:48:05 INFO - ##### Running download-and-extract step. 09:48:05 INFO - ##### 09:48:05 INFO - Running pre-action listener: _resource_record_pre_action 09:48:05 INFO - Running main action method: download_and_extract 09:48:05 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:48:05 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')]} 09:48:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/test_packages.json 09:48:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:48:05 INFO - Downloaded 1270 bytes. 09:48:05 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:48:05 INFO - Using the following test package requirements: 09:48:05 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:48:05 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:05 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'jsshell-linux-x86_64.zip'], 09:48:05 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:05 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:48:05 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:48:05 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:48:05 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:05 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:48:05 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:48:05 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:05 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:05 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')]} 09:48:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:48:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:05 INFO - Downloaded 21580798 bytes. 09:48:05 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 09:48:05 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:48:05 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 09:48:06 INFO - Return code: 0 09:48:06 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 09:48: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')]} 09:48:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:48:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:07 INFO - Downloaded 21580798 bytes. 09:48:07 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 09:48:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:48:07 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 09:48:08 INFO - Return code: 0 09:48:08 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')]} 09:48:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:48:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:09 INFO - Downloaded 11132038 bytes. 09:48:09 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 09:48:09 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:48:09 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 09:48:09 INFO - Return code: 0 09:48: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')]} 09:48:09 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:48:09 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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 09:48:10 INFO - Downloaded 57951413 bytes. 09:48:10 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:48:10 INFO - mkdir: /builds/slave/test-pgo/properties 09:48:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:48:10 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:48:10 INFO - Contents: 09:48:10 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:48:10 INFO - Running post-action listener: _resource_record_post_action 09:48:10 INFO - Running post-action listener: set_extra_try_arguments 09:48:10 INFO - ##### 09:48:10 INFO - ##### Running populate-webroot step. 09:48:10 INFO - ##### 09:48:10 INFO - Running pre-action listener: _resource_record_pre_action 09:48:10 INFO - Running main action method: populate_webroot 09:48:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:10 INFO - '--tppagecycles', 09:48:10 INFO - '7'], 09:48:10 INFO - 'tests': ['tsvgm']}}, 09:48:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:48:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:48:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:48:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'tests': ['damp', 'tps']}, 09:48:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['damp', 'tps']}, 09:48:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:48:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:10 INFO - 'other': {'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'other_l64': {'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'other_nol64': {'tests': ['a11yr', 09:48:10 INFO - 'ts_paint', 09:48:10 INFO - 'tpaint', 09:48:10 INFO - 'sessionrestore', 09:48:10 INFO - 'sessionrestore_no_auto_restore']}, 09:48:10 INFO - 'svgr': {'tests': ['tsvgx', 09:48:10 INFO - 'tsvgr_opacity', 09:48:10 INFO - 'tart', 09:48:10 INFO - 'tscrollx', 09:48:10 INFO - 'cart']}, 09:48:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['tsvgx', 09:48:10 INFO - 'tsvgr_opacity', 09:48:10 INFO - 'tart', 09:48:10 INFO - 'tscrollx', 09:48:10 INFO - 'cart']}, 09:48:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'tests': ['tp5o']}, 09:48:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'talos_options': ['--e10s'], 09:48:10 INFO - 'tests': ['tp5o']}, 09:48:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'talos_options': ['--xperf_path', 09:48:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:10 INFO - 'tests': ['tp5n']}, 09:48:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:10 INFO - 'talos_options': ['--e10s', 09:48:10 INFO - '--xperf_path', 09:48:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:10 INFO - 'tests': ['tp5n']}}, 09:48:10 INFO - 'talos.zip': {'path': '', 09:48:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:10 INFO - Running post-action listener: _resource_record_post_action 09:48:10 INFO - ##### 09:48:10 INFO - ##### Running create-virtualenv step. 09:48:10 INFO - ##### 09:48:10 INFO - Running pre-action listener: _resource_record_pre_action 09:48:10 INFO - Running main action method: create_virtualenv 09:48:10 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:48:10 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 09:48:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:48:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:10 INFO - Using real prefix '/usr' 09:48:10 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:48:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:13 INFO - Installing pip.................done. 09:48:13 INFO - Return code: 0 09:48:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:13 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:13 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 09:48:13 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 09:48:13 INFO - Using env: {'DISPLAY': ':0', 09:48:13 INFO - 'HOME': '/home/cltbld', 09:48:13 INFO - 'LANG': 'en_US.UTF-8', 09:48:13 INFO - 'LANGUAGE': 'en_US:en', 09:48:13 INFO - 'LOGNAME': 'cltbld', 09:48:13 INFO - 'MAIL': '/var/mail/cltbld', 09:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:13 INFO - 'MOZ_NO_REMOTE': '1', 09:48:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:13 INFO - 'NO_EM_RESTART': '1', 09:48:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:13 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:13 INFO - 'SHELL': '/bin/bash', 09:48:13 INFO - 'SHLVL': '1', 09:48:13 INFO - 'TERM': 'linux', 09:48:13 INFO - 'TMOUT': '86400', 09:48:13 INFO - 'USER': 'cltbld', 09:48:13 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:13 INFO - '_': '/tools/buildbot/bin/python'} 09:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:48:13 INFO - Cleaning up... 09:48:13 INFO - Return code: 0 09:48:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:13 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:13 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 09:48:13 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 09:48:13 INFO - Using env: {'DISPLAY': ':0', 09:48:13 INFO - 'HOME': '/home/cltbld', 09:48:13 INFO - 'LANG': 'en_US.UTF-8', 09:48:13 INFO - 'LANGUAGE': 'en_US:en', 09:48:13 INFO - 'LOGNAME': 'cltbld', 09:48:13 INFO - 'MAIL': '/var/mail/cltbld', 09:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:13 INFO - 'MOZ_NO_REMOTE': '1', 09:48:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:13 INFO - 'NO_EM_RESTART': '1', 09:48:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:13 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:13 INFO - 'SHELL': '/bin/bash', 09:48:13 INFO - 'SHLVL': '1', 09:48:13 INFO - 'TERM': 'linux', 09:48:13 INFO - 'TMOUT': '86400', 09:48:13 INFO - 'USER': 'cltbld', 09:48:13 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:13 INFO - '_': '/tools/buildbot/bin/python'} 09:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:13 INFO - Downloading/unpacking psutil>=0.7.1 09:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:15 INFO - Installing collected packages: psutil 09:48:15 INFO - Running setup.py install for psutil 09:48:15 INFO - building 'psutil._psutil_linux' extension 09:48:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:48:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:48:15 INFO - building 'psutil._psutil_posix' extension 09:48:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:48:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:16 INFO - Successfully installed psutil 09:48:16 INFO - Cleaning up... 09:48:16 INFO - Return code: 0 09:48:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:48:16 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')]} 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:16 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')]} 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:16 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:16 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 09:48:16 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 09:48:16 INFO - Using env: {'DISPLAY': ':0', 09:48:16 INFO - 'HOME': '/home/cltbld', 09:48:16 INFO - 'LANG': 'en_US.UTF-8', 09:48:16 INFO - 'LANGUAGE': 'en_US:en', 09:48:16 INFO - 'LOGNAME': 'cltbld', 09:48:16 INFO - 'MAIL': '/var/mail/cltbld', 09:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:16 INFO - 'MOZ_NO_REMOTE': '1', 09:48:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:16 INFO - 'NO_EM_RESTART': '1', 09:48:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:16 INFO - 'SHELL': '/bin/bash', 09:48:16 INFO - 'SHLVL': '1', 09:48:16 INFO - 'TERM': 'linux', 09:48:16 INFO - 'TMOUT': '86400', 09:48:16 INFO - 'USER': 'cltbld', 09:48:16 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:16 INFO - '_': '/tools/buildbot/bin/python'} 09:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:48:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:48:18 INFO - Installing collected packages: mozsystemmonitor 09:48:18 INFO - Running setup.py install for mozsystemmonitor 09:48:18 INFO - Successfully installed mozsystemmonitor 09:48:18 INFO - Cleaning up... 09:48:18 INFO - Return code: 0 09:48:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:48:18 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')]} 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:18 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')]} 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:18 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:18 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 09:48:18 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 09:48:18 INFO - Using env: {'DISPLAY': ':0', 09:48:18 INFO - 'HOME': '/home/cltbld', 09:48:18 INFO - 'LANG': 'en_US.UTF-8', 09:48:18 INFO - 'LANGUAGE': 'en_US:en', 09:48:18 INFO - 'LOGNAME': 'cltbld', 09:48:18 INFO - 'MAIL': '/var/mail/cltbld', 09:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:18 INFO - 'MOZ_NO_REMOTE': '1', 09:48:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:18 INFO - 'NO_EM_RESTART': '1', 09:48:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:18 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:18 INFO - 'SHELL': '/bin/bash', 09:48:18 INFO - 'SHLVL': '1', 09:48:18 INFO - 'TERM': 'linux', 09:48:18 INFO - 'TMOUT': '86400', 09:48:18 INFO - 'USER': 'cltbld', 09:48:18 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:18 INFO - '_': '/tools/buildbot/bin/python'} 09:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:48:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:20 INFO - Downloading docopt-0.6.1.tar.gz 09:48:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:20 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:20 INFO - Running setup.py install for blobuploader 09:48:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:21 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:48:21 INFO - Running setup.py install for requests 09:48:21 INFO - Running setup.py install for docopt 09:48:21 INFO - Successfully installed blobuploader requests docopt 09:48:21 INFO - Cleaning up... 09:48:21 INFO - Return code: 0 09:48:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48:21 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')]} 09:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 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')]} 09:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:21 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:21 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 09:48:21 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 09:48:21 INFO - Using env: {'DISPLAY': ':0', 09:48:21 INFO - 'HOME': '/home/cltbld', 09:48:21 INFO - 'LANG': 'en_US.UTF-8', 09:48:21 INFO - 'LANGUAGE': 'en_US:en', 09:48:21 INFO - 'LOGNAME': 'cltbld', 09:48:21 INFO - 'MAIL': '/var/mail/cltbld', 09:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:21 INFO - 'MOZ_NO_REMOTE': '1', 09:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:21 INFO - 'NO_EM_RESTART': '1', 09:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:21 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:21 INFO - 'SHELL': '/bin/bash', 09:48:21 INFO - 'SHLVL': '1', 09:48:21 INFO - 'TERM': 'linux', 09:48:21 INFO - 'TMOUT': '86400', 09:48:21 INFO - 'USER': 'cltbld', 09:48:21 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:21 INFO - '_': '/tools/buildbot/bin/python'} 09:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:21 INFO - Running setup.py (path:/tmp/pip-goP6en-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:21 INFO - Running setup.py (path:/tmp/pip-JmVRM7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:21 INFO - Running setup.py (path:/tmp/pip-ekPqnk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:22 INFO - Running setup.py (path:/tmp/pip-xsC_wv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:22 INFO - Running setup.py (path:/tmp/pip-JLaofy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:22 INFO - Running setup.py (path:/tmp/pip-wVqn6O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:22 INFO - Running setup.py (path:/tmp/pip-cu5UK3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:22 INFO - Running setup.py (path:/tmp/pip-GfETas-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:22 INFO - Running setup.py (path:/tmp/pip-HANZCv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:22 INFO - Running setup.py (path:/tmp/pip-hXLKxd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:22 INFO - Running setup.py (path:/tmp/pip-6BwZA0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:22 INFO - Running setup.py (path:/tmp/pip-b8n_dq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:22 INFO - Running setup.py (path:/tmp/pip-9jaNYW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:22 INFO - Running setup.py (path:/tmp/pip-CLXGwI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:22 INFO - Running setup.py (path:/tmp/pip-DRfE0g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:22 INFO - Running setup.py (path:/tmp/pip-jzRSnQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:22 INFO - Running setup.py (path:/tmp/pip-pG8S8F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:23 INFO - Running setup.py install for manifestparser 09:48:23 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Running setup.py install for mozcrash 09:48:23 INFO - Running setup.py install for mozdebug 09:48:23 INFO - Running setup.py install for mozdevice 09:48:23 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Running setup.py install for mozfile 09:48:23 INFO - Running setup.py install for mozhttpd 09:48:23 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Running setup.py install for mozinfo 09:48:23 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Running setup.py install for mozInstall 09:48:23 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:48:23 INFO - Running setup.py install for mozleak 09:48:23 INFO - Running setup.py install for mozlog 09:48:24 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Running setup.py install for moznetwork 09:48:24 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Running setup.py install for mozprocess 09:48:24 INFO - Running setup.py install for mozprofile 09:48:24 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Running setup.py install for mozrunner 09:48:24 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Running setup.py install for mozscreenshot 09:48:24 INFO - Running setup.py install for moztest 09:48:24 INFO - Running setup.py install for mozversion 09:48:24 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:48:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:24 INFO - Cleaning up... 09:48:24 INFO - Return code: 0 09:48:24 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48: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')]} 09:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:24 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:24 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 09:48:24 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 09:48:24 INFO - Using env: {'DISPLAY': ':0', 09:48:24 INFO - 'HOME': '/home/cltbld', 09:48:24 INFO - 'LANG': 'en_US.UTF-8', 09:48:24 INFO - 'LANGUAGE': 'en_US:en', 09:48:24 INFO - 'LOGNAME': 'cltbld', 09:48:24 INFO - 'MAIL': '/var/mail/cltbld', 09:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:24 INFO - 'MOZ_NO_REMOTE': '1', 09:48:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:24 INFO - 'NO_EM_RESTART': '1', 09:48:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:24 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:24 INFO - 'SHELL': '/bin/bash', 09:48:24 INFO - 'SHLVL': '1', 09:48:24 INFO - 'TERM': 'linux', 09:48:24 INFO - 'TMOUT': '86400', 09:48:24 INFO - 'USER': 'cltbld', 09:48:24 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:24 INFO - '_': '/tools/buildbot/bin/python'} 09:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:25 INFO - Running setup.py (path:/tmp/pip-zArmWW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:25 INFO - Running setup.py (path:/tmp/pip-11ByBW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:25 INFO - Running setup.py (path:/tmp/pip-ZX_42U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:25 INFO - Running setup.py (path:/tmp/pip-klkSTB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:25 INFO - Running setup.py (path:/tmp/pip-XGXEC_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:25 INFO - Running setup.py (path:/tmp/pip-CfzpNa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:25 INFO - Running setup.py (path:/tmp/pip-PqxNic-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:25 INFO - Running setup.py (path:/tmp/pip-9_cA9k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:25 INFO - Running setup.py (path:/tmp/pip-4DqQu6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:25 INFO - Running setup.py (path:/tmp/pip-k9kQ8Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:25 INFO - Running setup.py (path:/tmp/pip-JiZ7oh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:25 INFO - Running setup.py (path:/tmp/pip-uK7TJG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:25 INFO - Running setup.py (path:/tmp/pip-iwyYx8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:48:25 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)) 09:48:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:25 INFO - Running setup.py (path:/tmp/pip-wkynO5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:48:26 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)) 09:48:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:26 INFO - Running setup.py (path:/tmp/pip-XlFXOP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:48:26 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)) 09:48:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:26 INFO - Running setup.py (path:/tmp/pip-KDkGin-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:48:26 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)) 09:48:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:26 INFO - Running setup.py (path:/tmp/pip-NHdEdX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:48:26 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)) 09:48:26 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)) 09:48:26 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)) 09:48:26 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)) 09:48:26 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)) 09:48:26 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)) 09:48:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:28 INFO - Downloading blessings-1.6.tar.gz 09:48:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:28 INFO - Installing collected packages: blessings 09:48:28 INFO - Running setup.py install for blessings 09:48:28 INFO - Successfully installed blessings 09:48:28 INFO - Cleaning up... 09:48:28 INFO - Return code: 0 09:48:28 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:48:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:28 INFO - Reading from file tmpfile_stdout 09:48:28 INFO - Current package versions: 09:48:28 INFO - argparse == 1.2.1 09:48:28 INFO - blessings == 1.6 09:48:28 INFO - blobuploader == 1.2.4 09:48:28 INFO - docopt == 0.6.1 09:48:28 INFO - manifestparser == 1.1 09:48:28 INFO - mozInstall == 1.12 09:48:28 INFO - mozcrash == 0.16 09:48:28 INFO - mozdebug == 0.1 09:48:28 INFO - mozdevice == 0.47 09:48:28 INFO - mozfile == 1.2 09:48:28 INFO - mozhttpd == 0.7 09:48:28 INFO - mozinfo == 0.9 09:48:28 INFO - mozleak == 0.1 09:48:28 INFO - mozlog == 3.1 09:48:28 INFO - moznetwork == 0.27 09:48:28 INFO - mozprocess == 0.22 09:48:28 INFO - mozprofile == 0.27 09:48:28 INFO - mozrunner == 6.11 09:48:28 INFO - mozscreenshot == 0.1 09:48:28 INFO - mozsystemmonitor == 0.0 09:48:28 INFO - moztest == 0.7 09:48:28 INFO - mozversion == 1.4 09:48:28 INFO - psutil == 3.1.1 09:48:28 INFO - requests == 1.2.3 09:48:28 INFO - wsgiref == 0.1.2 09:48:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:48: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')]} 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:28 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 09:48:28 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 09:48:28 INFO - Using env: {'DISPLAY': ':0', 09:48:28 INFO - 'HOME': '/home/cltbld', 09:48:28 INFO - 'LANG': 'en_US.UTF-8', 09:48:28 INFO - 'LANGUAGE': 'en_US:en', 09:48:28 INFO - 'LOGNAME': 'cltbld', 09:48:28 INFO - 'MAIL': '/var/mail/cltbld', 09:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:28 INFO - 'MOZ_NO_REMOTE': '1', 09:48:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:28 INFO - 'NO_EM_RESTART': '1', 09:48:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:28 INFO - 'SHELL': '/bin/bash', 09:48:28 INFO - 'SHLVL': '1', 09:48:28 INFO - 'TERM': 'linux', 09:48:28 INFO - 'TMOUT': '86400', 09:48:28 INFO - 'USER': 'cltbld', 09:48:28 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:28 INFO - '_': '/tools/buildbot/bin/python'} 09:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 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)) 09:48:28 INFO - Cleaning up... 09:48:28 INFO - Return code: 0 09:48:28 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:48: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')]} 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:28 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 09:48:28 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 09:48:28 INFO - Using env: {'DISPLAY': ':0', 09:48:28 INFO - 'HOME': '/home/cltbld', 09:48:28 INFO - 'LANG': 'en_US.UTF-8', 09:48:28 INFO - 'LANGUAGE': 'en_US:en', 09:48:28 INFO - 'LOGNAME': 'cltbld', 09:48:28 INFO - 'MAIL': '/var/mail/cltbld', 09:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:28 INFO - 'MOZ_NO_REMOTE': '1', 09:48:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:28 INFO - 'NO_EM_RESTART': '1', 09:48:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:28 INFO - 'SHELL': '/bin/bash', 09:48:28 INFO - 'SHLVL': '1', 09:48:28 INFO - 'TERM': 'linux', 09:48:28 INFO - 'TMOUT': '86400', 09:48:28 INFO - 'USER': 'cltbld', 09:48:28 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:28 INFO - '_': '/tools/buildbot/bin/python'} 09:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:28 INFO - Downloading/unpacking jsonschema 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:30 INFO - Downloading/unpacking functools32 (from jsonschema) 09:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:30 INFO - warning: no files found matching '*.txt' 09:48:30 INFO - no previously-included directories found matching 'build' 09:48:30 INFO - no previously-included directories found matching 'dist' 09:48:30 INFO - no previously-included directories found matching '.git*' 09:48:30 INFO - Installing collected packages: jsonschema, functools32 09:48:30 INFO - Running setup.py install for functools32 09:48:31 INFO - warning: no files found matching '*.txt' 09:48:31 INFO - no previously-included directories found matching 'build' 09:48:31 INFO - no previously-included directories found matching 'dist' 09:48:31 INFO - no previously-included directories found matching '.git*' 09:48:31 INFO - Successfully installed jsonschema functools32 09:48:31 INFO - Cleaning up... 09:48:31 INFO - Return code: 0 09:48:31 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:48: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')]} 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:31 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 0x7ff2f32c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24968a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2563600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', '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 09:48:31 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 09:48:31 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 09:48:31 INFO - Using env: {'DISPLAY': ':0', 09:48:31 INFO - 'HOME': '/home/cltbld', 09:48:31 INFO - 'LANG': 'en_US.UTF-8', 09:48:31 INFO - 'LANGUAGE': 'en_US:en', 09:48:31 INFO - 'LOGNAME': 'cltbld', 09:48:31 INFO - 'MAIL': '/var/mail/cltbld', 09:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:31 INFO - 'MOZ_NO_REMOTE': '1', 09:48:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:31 INFO - 'NO_EM_RESTART': '1', 09:48:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:31 INFO - 'SHELL': '/bin/bash', 09:48:31 INFO - 'SHLVL': '1', 09:48:31 INFO - 'TERM': 'linux', 09:48:31 INFO - 'TMOUT': '86400', 09:48:31 INFO - 'USER': 'cltbld', 09:48:31 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:31 INFO - '_': '/tools/buildbot/bin/python'} 09:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:31 INFO - Downloading/unpacking flake8 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:48:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:48:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:48:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:48:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:48:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:48:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:48:33 INFO - no previously-included directories found matching 'docs/_build' 09:48:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:48:33 INFO - Running setup.py install for pep8 09:48:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:48:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:48:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:48:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:48:33 INFO - no previously-included directories found matching 'docs/_build' 09:48:33 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:48:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:48:33 INFO - Cleaning up... 09:48:34 INFO - Return code: 0 09:48:34 INFO - Running post-action listener: _resource_record_post_action 09:48:34 INFO - Running post-action listener: _start_resource_monitoring 09:48:34 INFO - Starting resource monitoring. 09:48:34 INFO - ##### 09:48:34 INFO - ##### Running install step. 09:48:34 INFO - ##### 09:48:34 INFO - Running pre-action listener: _resource_record_pre_action 09:48:34 INFO - Running main action method: install 09:48:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:48:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:48:34 INFO - Reading from file tmpfile_stdout 09:48:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:48:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:48:34 INFO - Reading from file tmpfile_stdout 09:48:34 INFO - Output received: 09:48:34 INFO - Usage: mozinstall [options] installer 09:48:34 INFO - Options: 09:48:34 INFO - -h, --help show this help message and exit 09:48:34 INFO - -d DEST, --destination=DEST 09:48:34 INFO - Directory to install application into. [default: 09:48:34 INFO - "/builds/slave/test-pgo"] 09:48:34 INFO - --app=APP Application being installed. [default: firefox] 09:48:34 INFO - mkdir: /builds/slave/test-pgo/build/application 09:48:34 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'] 09:48:34 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 09:48:48 INFO - Reading from file tmpfile_stdout 09:48:48 INFO - Output received: 09:48:48 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:48:48 INFO - Running post-action listener: _resource_record_post_action 09:48:48 INFO - ##### 09:48:48 INFO - ##### Running run-tests step. 09:48:48 INFO - ##### 09:48:48 INFO - Running pre-action listener: _resource_record_pre_action 09:48:48 INFO - Running main action method: run_tests 09:48:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:48:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:48:48 INFO - Python 2.7.3 09:48:48 INFO - Return code: 0 09:48:48 INFO - grabbing minidump binary from tooltool 09:48:48 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')]} 09:48:48 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 0x25f2350>, '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 0x25f3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25f36e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:48:48 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 09:48:48 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 09:48:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:48:48 INFO - Return code: 0 09:48:48 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:48:48 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:48:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:48:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:48:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:48:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:48:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:48:50 INFO - Return code: 0 09:48:50 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/1453908961/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:48:50 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/1453908961/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost 09:48:50 INFO - Using env: {'DISPLAY': ':0', 09:48:50 INFO - 'HOME': '/home/cltbld', 09:48:50 INFO - 'LANG': 'en_US.UTF-8', 09:48:50 INFO - 'LANGUAGE': 'en_US:en', 09:48:50 INFO - 'LOGNAME': 'cltbld', 09:48:50 INFO - 'MAIL': '/var/mail/cltbld', 09:48:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:48:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:50 INFO - 'MOZ_NO_REMOTE': '1', 09:48:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:50 INFO - 'NO_EM_RESTART': '1', 09:48:50 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:48:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:48:50 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:48:50 INFO - 'SHELL': '/bin/bash', 09:48:50 INFO - 'SHLVL': '1', 09:48:50 INFO - 'TERM': 'linux', 09:48:50 INFO - 'TMOUT': '86400', 09:48:50 INFO - 'USER': 'cltbld', 09:48:50 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738', 09:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:50 INFO - '_': '/tools/buildbot/bin/python'} 09:48:50 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/1453908961/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost'] with output_timeout 3600 09:48:50 INFO - mozversion INFO | application_buildid: 20160127073601 09:48:50 INFO - mozversion INFO | application_changeset: 07bd7f4a0a1b808f015fa83e9c7cb639983134ce 09:48:50 INFO - mozversion INFO | application_display_name: Nightly 09:48:50 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:48:50 INFO - mozversion INFO | application_name: Firefox 09:48:50 INFO - mozversion INFO | application_remotingname: firefox 09:48:50 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:48:50 INFO - mozversion INFO | application_vendor: Mozilla 09:48:50 INFO - mozversion INFO | application_version: 45.0 09:48:50 INFO - mozversion INFO | platform_buildid: 20160127073601 09:48:50 INFO - mozversion INFO | platform_changeset: 07bd7f4a0a1b808f015fa83e9c7cb639983134ce 09:48:50 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:48:50 INFO - mozversion INFO | platform_version: 45.0 09:48:50 INFO - 2016-01-27 09:48:50,219 DEBUG : using testdate: 1453916930 09:48:50 INFO - 2016-01-27 09:48:50,220 DEBUG : actual date: 1453916930 09:48:50 INFO - 2016-01-27 09:48:50,228 INFO : starting webserver on 'localhost:50773' 09:48:50 INFO - 2016-01-27 09:48:50,229 INFO : Starting test suite talos-linux64-ix-037 09:48:50 INFO - 2016-01-27 09:48:50,229 INFO : Starting test dromaeo_css 09:48:50 INFO - 2016-01-27 09:48:50,229 DEBUG : operating with platform_type : linux_ 09:48:50 INFO - 2016-01-27 09:48:50,230 INFO : Initialising browser for dromaeo_css test... 09:48:50 INFO - 2016-01-27 09:48:50,247 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4DADMR/profile http://localhost:50773/getInfo.html 09:48:54 INFO - 2016-01-27 09:48:54,113 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:48:54 INFO - 2016-01-27 09:48:54,113 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:48:54 INFO - 2016-01-27 09:48:54,113 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:48:54 INFO - 2016-01-27 09:48:54,113 DEBUG : BROWSER_OUTPUT: __metrics 09:48:54 INFO - 2016-01-27 09:48:54,270 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:48:55 INFO - 2016-01-27 09:48:55,284 INFO : Browser initialized. 09:48:55 INFO - 2016-01-27 09:48:55,284 INFO : Running cycle 1/1 for dromaeo_css test... 09:48:55 INFO - 2016-01-27 09:48:55,284 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4DADMR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:49:07 INFO - 2016-01-27 09:49:07,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 134569984 09:49:07 INFO - 2016-01-27 09:49:07,032 DEBUG : BROWSER_OUTPUT: 09:49:57 INFO - 2016-01-27 09:49:57,781 DEBUG : BROWSER_OUTPUT: 1453916997778 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:52:06 INFO - 2016-01-27 09:52:06,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50773/tests/dromaeo/cssquery-ext.html) 09:52:07 INFO - 2016-01-27 09:52:07,071 DEBUG : BROWSER_OUTPUT: RSS: Main: 235896832 09:52:07 INFO - 2016-01-27 09:52:07,071 DEBUG : BROWSER_OUTPUT: 09:55:09 INFO - 2016-01-27 09:55:09,093 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-ext.html (next: http://localhost:50773/tests/dromaeo/cssquery-jquery.html) 09:55:09 INFO - 2016-01-27 09:55:09,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 220450816 09:55:09 INFO - 2016-01-27 09:55:09,659 DEBUG : BROWSER_OUTPUT: 09:58:11 INFO - 2016-01-27 09:58:11,436 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50773/tests/dromaeo/cssquery-mootools.html) 09:58:12 INFO - 2016-01-27 09:58:12,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 212586496 09:58:12 INFO - 2016-01-27 09:58:12,002 DEBUG : BROWSER_OUTPUT: 10:01:13 INFO - 2016-01-27 10:01:13,693 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50773/tests/dromaeo/cssquery-prototype.html) 10:01:14 INFO - 2016-01-27 10:01:14,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 202833920 10:01:14 INFO - 2016-01-27 10:01:14,275 DEBUG : BROWSER_OUTPUT: 10:04:16 INFO - 2016-01-27 10:04:16,710 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50773/tests/dromaeo/cssquery-yui.html) 10:04:17 INFO - 2016-01-27 10:04:17,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 186982400 10:04:17 INFO - 2016-01-27 10:04:17,276 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/dromaeo/cssquery-yui.html (next: http://localhost:50773/tests/dromaeo/cssquery-dojo.html) 10:07:19 INFO - 2016-01-27 10:07:19,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 243793920 10:07:19 INFO - 2016-01-27 10:07:19,609 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,609 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:07:19 INFO - 2016-01-27 10:07:19,609 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:07:19 INFO - 2016-01-27 10:07:19,609 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:07:19 INFO - 2016-01-27 10:07:19,609 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:07:19 INFO - 2016-01-27 10:07:19,611 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3187.1713354527824;3292.226326813197;3322.2192784695594;3325.6508066653;3344.26426186239;5472.753726082326;5528;5547.528460080898;5561.666300022001;5576.721163941807;5542.75057622407;5558.416366281543;5559.555235581154;5578.581606379527;5588.30146231721;4939.407271127469;5041.243813427996;5056.342675452186;5075.568576670988;5171.7414129293575;4948.38145231846;4997.100521906055;5026.9946010797985;5056.367954005749;5096.923546146811;4983;5049;5057.393112826466;5068.163752980754;5114.590832733373;5154.355705536808;5174.353205849269;5182.689038657692;5192.299039629677;5221.2951251580935;4912.778924948386;4925.482824303433;5041.445441001487;5086.109930762139;5090.032893750175;5300.734963251861;5302.840914772563;5308.893822123575;5324.361076670785;5362.490563396471;5858.941410585925;5864.384239654817;5905.852353691149;5920.97039514804;5922.31893332268;5912.3496415394275;5946;5972.283326000865;6032.577719559633;6053.092036194562;5606.719664016824;5628.04323265046;5629.690367029812;5636.436356364331;5640.971795141039;6029.638221706711;6032.517398608188;6038.426349496791;6038.63768173911;6038.758449662052;5640.746166422521;5745.2818397700285;5795.9420405796245;5893.823185304362;5926.170336152942;5866.73599688015;5966.134910437962;5975.223220981245;5975.372585878507;5979.5515336349945;5646.661200327993;5935.228420305332;5949.434803693643;5952.791652292236;5956.493698035629;5787.421257874263;5787.623804452697;5900.409959004065;5906.940930590639;5934.465898069108;4539.137563862855;4560.657950653715;4561.429821272341;4576.885577860514;4592.265237561974;4055.7566546007333;4081.8775436736946;4104;4126.587341265909;4137.834486620502;5577.1355439906065;5659.490645841894;5680.914786278209;5711.200431939532;5711.8000869969355;6024.277086749618;6028.879422411527;6036.667983260875;6039.54703397247;6043;5427.294451721251;5546.306711661043;5686.914696279559;5695.345035320908;5696.11710184922;5129.64092513528;5207.270982062514;5207.505286997729;5214.0614689355525;5240.4759524047295;6169.9383006170265;6179.351168127372;6210.658413787285;6213.844653883689;6218.906716399258;5792.797252096157;5810.76756929735;5966.254218222723;6075.27096748393;6089.299730530868;6179.134921111138;6203.348648392034;6205.844853878689;6211.13044173807;6216.129741836056;5927.911081333869;5966.612170208922;5983.37174596661;5985.042393217064;6027.698615069141;5778.8844223114465;5945.197398351167;5947.464728174485;5991.101334799815;6117.021276595723;5834.591578589458;5836.445537673914;5841.474267315962;5844.824655260349;5847.356790753099;5841.211436456024;5854;5861.706914654165;5864.06175011996;5877.265341832271;5508;5583.609147359808;5610.663360198401;5676.545876329801;5718.027935250934;5824.126381042708;5825.533957283492;5828.184054232495;5832.883342333214;5842.503387212135;5488.4237155098135;5669.34802497718;5699.430056994267;5728.054870946247;5733.5126514245085 10:07:19 INFO - 2016-01-27 10:07:19,612 DEBUG : BROWSER_OUTPUT: |1;ext.html;601.9969900150498;615.2094558492338;633.4805459523191;645.1935081148563;709.0250905005618;30321.696783032192;30883.22791930203;40280.1943961121;40892.18215635685;41008.38487422691;45993.31010034844;46470.070598588005;46477.30284045742;46485.30272045922;46587.76706116473;8016.799580010488;8157;8969.013408525074;9178.816423671522;9185.402948808307;10863.891361086367;11071.833922491169;11100.056495197878;11142.387168705718;11143.052840508566;10839.945800270987;10930.672079837617;10948.233623646347;10948.835767463494;10959.452027398638;10798.190135739811;10914.181436392262;10915.454227288605;10920.890791092106;10928.726781830439;10690.358578485315;10862.891371086267;10869.782604347947;10875.184361172944;10882.29265097774;41557;42098;42128;42170.78914605438;42249;3625.546806649169;4089.9591004089875;4278.187144442546;4285.35719642053;4415.779211039435;31664.208394790083;31985.52021719676;32043;32196.034118976233;32200.677993220237;3561.7506774525796;3602.9819850900576;3695.297893400272;3696.741228114033;3761.022134245116;294434;294534;294758;295226;296968;54737.72631136819;55826;62172.37827621756;62443.375566243754;62672.68663656653;55560.44439555633;55562.72218638962;55643.44356556464;55741.16388254098;56091.71954140203;16290.674186516204;16501.669966600602;16504.83495165057;16538.6692266154;16654.916725416297;12554.372281386077;12570.182938108994;12595.496180152875;12597.370131493388;12597.811032834516;19914.800851991295;20318;20319.4920126998;20326.593468130555;20384.89807550953;8185.813057106686;8338.291245244221;8341.95829020851;8377.748667539983;8397.874031889527;1014.0974532665933;1045.6174276216586;1055.2718624149313;1097.7969075721016;1152.147410915933;28879;29691.554626680616;30279.69720302813;30428.695713043027;30479.695203047686;1129.3224065560598;1149.2357582207737;1169.9649010529697;1212.1878341511208;1225.8528976522875;13895.722085558435;15829.208539572975;15945.760813587805;16275.10486923207;16421.83578164227;339267;339493;339762;340131;341026;55693.443065569634;55829.7208513963;55903.72048139733;55907.440925591036;55918.440815592134;1450.55032939789;1489.7616381378928;1513.902420744951;1521.9391224351123;1530.8163020437619;29720.554191686708;29768.605378360753;30035.549466758453;30078.54882176813;30219.244518887204;45199.77400112979;45299.320510193036;45510.54489455195;45593.31610025919;45658.771706141255;208172;209194;210079;211033;211328;30377;30809.229769255053;31336.52995205028;34483.65516344804;34501.827490862386;39784;39827.40258896061;39868.401973969834;40731.77804665865;41173.79413102915;34532.30935381228;34688.479672804424;34842.30315393729;34992.65007349894;35285;37453.25093498169;38864.41703374395;39265.80367098146;42574.361384579875;42754.727349935805;37446;37499.625003749614;37746.43380349352;37777.05557361088;38860.22279554336;31240.531392028683;31260.374792503568;31319.68680313259;31323.530147047357;32163;24391.634125487777;24938.75061249437;24999.750002499743;25161.622575661746;26216.34459138537 10:07:19 INFO - 2016-01-27 10:07:19,613 DEBUG : BROWSER_OUTPUT: |2;jquery.html;368.1954928481269;371.3371631637527;372.3799873211104;372.474810517171;373.771999080561;15201.935864489462;16755.246013929373;17250.65498690027;17587;17604.471865844014;12229.694257643563;12484.313362765068;12503.31231782254;13792.310384480761;17958.820411795878;3849.2301539692035;5133.1786914093755;5189.221616757479;5244.947550524503;5246.711430871301;4012.899677508071;5512.972435137839;5628.549716022729;5651.124075768242;5655.802046928358;5537.750801213955;5578.135389014709;5620.185073164389;5623.80316688916;6053.818385448443;6076.544259180557;6105.938940610581;6112.724927378256;6114.908276375858;6116.847078823021;5245;5398.974194902956;5472.671639701591;5475.479829416199;5552.555795536347;14024.649383765385;14146.29268536569;14150;20009;22713.432164195863;2067.7622073461594;2132.605467988427;2154.666026765854;2161.459635091227;2259.8870056497276;9854.113129818279;10001.499925003793;11103.72240693981;11985.880141198564;13182.472701091945;1638.1236038719228;1719.9484015479431;1756.9472915812546;1774.8935063896208;1804.8195180481978;67953;71839;72279.63860180718;73428.6328568359;73562.63218683926;10623.575056997788;10899.346039237671;11038.613648522263;11060;11092.057175140042;14587.854121458862;14651.560453186421;14681.92659036698;14699.485518007032;14708.632284192767;7789.493682910593;7853.332466740277;7858.489198202213;7924.6037698116015;7961.960190199012;7320.487565870444;7331.853362932711;7366.042414486083;7401.444891633041;7421.666025028887;4268.786560671954;4669.2996050592;4710.36410084641;4720.787564559603;4750.643701722384;1814.1926842555201;1850.463365623954;1911.4934542346289;1932.603816217672;1933.951651208731;454.14393867559886;464.8721601559598;475.80016393114977;970.4080510888352;1063.7021633942509;10347.689569312932;10711.410872401937;10785.946070269598;10957.397343146204;11126.61056863006;483.8935434204469;486.03036941302327;932.2774849491698;939.5396255834592;975.317277905469;8649.394542382099;9023.142801433853;9086.636534538544;9246.121618446237;9471.715848524555;619627;619863;623402;644062;645334;83463.5826820862;83861.16138838533;85726;88410;88685;1216.4829947272374;1219.8719134490927;1220.1337050693928;1226.6504046346927;1239.4856134704178;14942;14982.700345992802;15126.470573529874;15145.848541514444;15275.541733748005;33877.83061084679;34273.14317142092;34752.478712818825;35048.47427288542;35879.46180807342;51727;52760;53361.7331913338;54597.454025459236;54805.45194548163;10309.175265978854;10344.482775861328;10518.211134165122;10601.363918164934;10622.521986510472;12315.44580493862;12428.875711243134;17616.471505854846;18158.09209539544;18337.541561460537;14606.488772892897;14706.132304200579;14790.482333118289;14900.7764883529;15688.686226275639;15184.46854360092;15374.846251537341;15513.146776926926;15840.366385344458;15898.364065437252;12750;13863;14537.563873083824;14678;15664.451744188898;10302.381857088598;10321.122704570196;10324.380537167794;10384.948075259575;10398.792024159626;11126.833097503706;11924.642260732191;15985.68028639444;16096.034237945762;16146.67706645884 10:07:19 INFO - 2016-01-27 10:07:19,614 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1559.8752099832016;1560.968780624387;1754.578901063745;1779.7419374190742;1814.582645991422;7174.390176834969;7388.076490438695;7390.224026477224;7393.482456228066;7398.223186565408;6729.831754206148;6899.517033807636;7174.7130114795345;7474.7757567273065;7643.426742994284;2188.912443502258;2224.177054489841;2245;2245.146844199202;2247.3595025417676;2024.6659301215354;2260.7400148982915;2261.2989973108306;2363.196513185517;2371.703537057868;1987.1554589299562;2159.8920053997317;2231.8214542836536;2233.1737257214854;2233.2853486884205;2235.58641651295;2254.233560589399;2258.5708715344113;2287.645414960667;2288.313505948209;2274.8293877959054;2278.1798552521077;2298.8735619540917;2300.907963681451;2301.2635956493928;7603.695852165907;7700.422468314899;7715.344195743369;7717;7717.263615686482;4535;4664.300354946785;4674.252119660872;4677.906441871178;4678.812847486097;2933.369325594986;2979.3594377208788;3022.335086281015;3032.1813110460052;3077.061496243645;931.9860202096974;936.0499093420184;943.4433684126329;953.8759961205068;972.6936015155203;10361.89638103617;10376.429296388696;10492.685219443429;10502.317349372266;10504.212184086224;4083.734557253769;4094.303968325392;4134.462519872457;4185.058361868555;4188.141431006637;9008.864867027;9009.189172974464;9013.909860901307;9023;9025.548722563977;4702.858914232578;4709.105269998689;4733.5503127202865;4739.383880095635;4744.5492678195515;4307.633851122627;4342;4349.543297953669;4351.912961740811;4352.194843953878;3662.9450558241647;3694.852205911787;3712.535933008374;3719.795411252407;3737.588865224823;1095.5727266366123;1117.279354816139;1133.7109037195462;1145.410113791398;1147.7015975846173;1000.9099181073593;1031.231732359397;1164.132721122769;1234.7592219517287;1369.6233535777742;7099.148102227766;7146.285371462916;7186.245444228385;7200.603966781772;7215.314545118205;1167.6788883057227;1172.384498138484;1174.2308787744041;1180.226951346869;1181.6277872470143;5721.256236689287;5777.277840269966;5798.623089499169;5802.187693722883;5805.593608447452;8759.518226497476;8879.467231966102;9104.635814567344;9112.589933453011;9112.95443522778;8884.156005179497;8982.730518084469;9010.369274150878;9016.23362014223;9026.819463610822;7235.38499227561;7254.383377412873;7355.896615507717;7366.815829604303;7377.852442951111;7392.075990501187;7442.4418168636485;7459.142198647225;7462.514936529108;7472.9626351870065;8060.919390806252;8149.2665660088505;8369.916300837158;8382.203690649378;8401.915980840113;8280.410722762232;8412.158784121539;8506.74479765608;8529.658813647384;8561.48631082137;6672.199336079701;6924.272951340036;6928.792136235921;6930.480213984072;6932.272111428228;7539.283768042027;7558.281963213486;7570.432217583593;7575.204603516551;7599.848003039798;6184;7038.648067596498;7234.493585448968;7305.086864141982;7330.853382932418;6157.322694503511;6600.438962688226;6994.790156295319;7094.290570942864;7106.360427561544;6888.965555172393;6901.861962760817;6909.99805028258;6922.58464492132;6926.757563485254;6470.6441145737435;6530.771423000172;6539.803805885831;6557.344265573404;6565.44193743518;6600.207975043026;6639.767608133692;6644.235912870082;6650.90023649636;6654.001899715082 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: |4;prototype.html;330.47289573130877;337.5645417411539;341.46901568061656;342.4144712541554;350.41305812763613;6178.28949670788;6234.812955611334;6251.656158911258;6285.748570057192;6290.49676025918;6138.079288106786;6174.35169307223;6177.9382206177925;6183.134361189426;6186.319504854462;4702.741349225791;4782.473927867941;4788.928166077511;4794.208955522335;4796.544328288807;4764.094821983829;4771.689840160396;4787.305840653104;4792.736399498043;4794.280857871312;4728.810847566093;4729.1487532244355;4739.881502962419;4749.928751068736;4754.429468463789;4548.385967894342;4620.468646105707;4624.838130665445;4744.881377965544;4755.025219829944;4141.337386018223;4176.728512646669;4203.201391735561;4204.1801848639525;4205.873823785291;5845.503132233765;5848.97075514624;5858.882822343572;5860.208871802337;5879;5298.443663415363;5388.299521062276;5466.808661696821;5550.555955523549;5566.4990150886615;6498.967505162444;6523.4455071319;6531.673416329212;6532.248791389004;6540.37866402691;4658.277966915133;4661.741329840958;4662.626989840838;4668.323093151474;4669.813207471697;6627.304133066055;6769.627670478122;6790.728370865159;6805.421539169127;6807.625580593016;4792.257200133985;4795.880102997383;4803.423589169252;4804.711717296974;4806.375171227718;4848.878778030577;4864.854054378375;4870.464248932614;4871.780769865364;4871.805127794849;5553;5554.91667624986;5560.304961879765;5566.053770858964;5568;6450.645214513247;6585.440237579859;6604.603723776589;6604.966975165094;6614.272430032693;1691.6362981958937;1717.7165767648198;1717.8110407855127;1718.3642052440428;1718.458685514059;1149.4654985431814;1152.7809716154072;1166.859976802789;1173.049829637979;1174.2191442690514;6459.935400645933;6464;6470.288268290485;6475.579087359401;6478.643674597943;6816.625085620339;6818.28408017151;6820.590764554143;6822.147231596051;6836.350546698056;6363.75746870674;6425.325340839238;6444.645544495098;6455.451286640594;6459.483241340681;3669.7798132112157;3694.2980833641523;3696.833642486041;3696.907577310535;3701.4632878232505;7009.264027277164;7014.719411223494;7017.087778588853;7020.333068358498;7020.684069216897;6679.933200668028;6754.864902701918;6945.340192681687;6951.652417379212;6957.8956315655305;6416.839579010469;7063.964680176669;7066.364027237573;7080.90246011869;7102.573845569385;6870.484713646397;6882.518223724292;6887.311268873073;6887.621180835102;6892.345227203407;6715.093462382712;6763.086983257394;6764.594124352555;6767.864642707217;6769.052332673528;7119.181294151239;7119.430445564446;7130.073090498202;7131.643417828984;7136.7145314186855;4644.256918892961;4688.296755486568;4690.882727931829;4691.272852707836;4693.366395536559;6889.414399776075;6895.103636527219;6900.516963812685;6906.447484201352;6911.205211400552;6399.104125422537;6683.532152749261;6696.129503164558;6696.330366963265;6696.732130714911;6521.836954075996;6523.021546767833;6524.3149469305035;6529.379708927644;6536.607803531803;6455.096286519797;6550.410463058157;6578.506612004023;6605.636689982097;6605.834854128493;6573.868522629617;6697.330266973264;6722.294159113419;6730.932690673227;6735.831604209934;6495.220573531206;6560.343965603593;6571.967140164268;6581.967090164519;6584.736610535717 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: |5;yui.html;810.3841080778608;859.1408591408596;863.5250612163309;880.4497189256715;912.5209265135803;12728.045396595258;13107.148035377717;13208.273544955024;13223.933880330596;13252.13861099028;13270.469181232764;13364.799528007063;13365.599032029055;13403;13405.731885362311;722.4220623501178;724.3625609463664;877.4647465046332;890.4034297021011;899.1008991008991;892.6830975003877;899.9415038022507;907.7503686486203;907.8774365460645;908.7546362482126;757.2843662738715;925.8518637018045;935.163029088965;936.967206147785;937.5499760115149;757.5000499670218;926.4672813132476;931.0503286647615;932.049309704098;932.9626814927428;687.7352219395568;688.1810645332089;688.4389222783416;690.9516333856632;692.1348314606741;11707.824382634268;11811.350375729331;11905.642830715093;11926.522939082426;11933;11874.346910919896;11956.103292253028;12945.611631651065;13049.282289474075;13152.6711832204;10204.438755868425;10348.844767328495;10356.948215258875;10495.160387169008;10546.525406356732;3468.757186996911;3494.52823868777;3497.9125521862156;3502.387082260594;3510.7717998330027;47766.761166193945;49858;50008.49991500075;50106.24840627394;50168.498315016746;9333.766655833659;9388;9425.575849086808;9451.952740236255;9488.667896623723;18986.52533686669;19067.665263431703;19090.61818763617;19120.713189302172;19132.713009304873;7400.407967362598;7451.105867295959;7453.813654658568;7508.699652013967;7699.960505331708;4218.0720241546815;4263.16868210696;4290.485141783006;4306.375575541591;4331.631811296075;3030.10611869499;3055.3278278778635;3063.8774449022235;3085.1515833146063;3158.873645054218;530.2947080383082;542.9077056900336;546.2216341713043;563.8787660652979;567.9943200567941;630.9432151106422;643.8744444833212;654.2639530528156;671.6406722403491;683.641088428579;8577.099404562556;9153.496557689392;9199.080091990882;9306.302027347841;9551.14039736427;491.69514900761754;613.3467856732566;686.0806519264111;694;719.133444199738;3845.288621605012;4028.71798974069;4118.485189351331;4158.93947043502;4180.937285940713;56000.719996399755;58492;58809;61539.6923015391;61782.69108654518;53992.73003634957;54736.17895731567;54966.17550736742;55654;55828.44171558314;48000;48497.272540911916;49362.259566106535;49451.50548494469;50360.49639503558;8591.828163436821;8809.779755506164;8858.202761751474;8897.154770296811;8921.420107693239;34916.65083349134;38728.6127138725;38791.22417551577;38874.22251555009;40827.7958610205;46179.3073103897;46892.29661555011;47052.52947470482;47076;47664.761676191396;10969.341839489656;11107.05590024857;11268.887311126784;11405.885941140814;11520.596779112691;11918.642440726791;12021.699457513632;12115.273083615011;12191;12440.440180191732;11920.642380728592;12093.516259349879;12158.878411216128;12461;12669.619911402673;11269.211155219384;11618.0705543558;11644.301341919512;11679.708007299885;11925.344106073902;10740.563498235442;11057.447127643747;11200.159988001096;11408.258463199865;11438.599649012245;8526.957365213135;8606.913930860612;8681.435706679045;8867.68963086289;8911.910880891368;9471;9989.850152247867;9989.850152247867;10111.797764044532;10458.215633827342 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,790 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: __startTimestamp1453918039607__endTimestamp 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:07:19 INFO - 2016-01-27 10:07:19,616 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,617 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5526.45 Median:5711.20 stddev:616.95 (10.8%) stddev-sans-first:590.92 10:07:19 INFO - 2016-01-27 10:07:19,618 DEBUG : BROWSER_OUTPUT: Values: 3187.2 3292.2 3322.2 3325.7 3344.3 5472.8 5528.0 5547.5 5561.7 5576.7 5542.8 5558.4 5559.6 5578.6 5588.3 4939.4 5041.2 5056.3 5075.6 5171.7 4948.4 4997.1 5027.0 5056.4 5096.9 4983.0 5049.0 5057.4 5068.2 5114.6 5154.4 5174.4 5182.7 5192.3 5221.3 4912.8 4925.5 5041.4 5086.1 5090.0 5300.7 5302.8 5308.9 5324.4 5362.5 5858.9 5864.4 5905.9 5921.0 5922.3 5912.3 5946.0 5972.3 6032.6 6053.1 5606.7 5628.0 5629.7 5636.4 5641.0 6029.6 6032.5 6038.4 6038.6 6038.8 5640.7 5745.3 5795.9 5893.8 5926.2 5866.7 5966.1 5975.2 5975.4 5979.6 5646.7 5935.2 5949.4 5952.8 5956.5 5787.4 5787.6 5900.4 5906.9 5934.5 4539.1 4560.7 4561.4 4576.9 4592.3 4055.8 4081.9 4104.0 4126.6 4137.8 5577.1 5659.5 5680.9 5711.2 5711.8 6024.3 6028.9 6036.7 6039.5 6043.0 5427.3 5546.3 5686.9 5695.3 5696.1 5129.6 5207.3 5207.5 5214.1 5240.5 6169.9 6179.4 6210.7 6213.8 6218.9 5792.8 5810.8 5966.3 6075.3 6089.3 6179.1 6203.3 6205.8 6211.1 6216.1 5927.9 5966.6 5983.4 5985.0 6027.7 5778.9 5945.2 5947.5 5991.1 6117.0 5834.6 5836.4 5841.5 5844.8 5847.4 5841.2 5854.0 5861.7 5864.1 5877.3 5508.0 5583.6 5610.7 5676.5 5718.0 5824.1 5825.5 5828.2 5832.9 5842.5 5488.4 5669.3 5699.4 5728.1 5733.5 10:07:19 INFO - 2016-01-27 10:07:19,618 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,618 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46077.24 Median:32014.26 stddev:74858.06 (233.8%) stddev-sans-first:74990.19 10:07:19 INFO - 2016-01-27 10:07:19,618 DEBUG : BROWSER_OUTPUT: Values: 602.0 615.2 633.5 645.2 709.0 30321.7 30883.2 40280.2 40892.2 41008.4 45993.3 46470.1 46477.3 46485.3 46587.8 8016.8 8157.0 8969.0 9178.8 9185.4 10863.9 11071.8 11100.1 11142.4 11143.1 10839.9 10930.7 10948.2 10948.8 10959.5 10798.2 10914.2 10915.5 10920.9 10928.7 10690.4 10862.9 10869.8 10875.2 10882.3 41557.0 42098.0 42128.0 42170.8 42249.0 3625.5 4090.0 4278.2 4285.4 4415.8 31664.2 31985.5 32043.0 32196.0 32200.7 3561.8 3603.0 3695.3 3696.7 3761.0 294434.0 294534.0 294758.0 295226.0 296968.0 54737.7 55826.0 62172.4 62443.4 62672.7 55560.4 55562.7 55643.4 55741.2 56091.7 16290.7 16501.7 16504.8 16538.7 16654.9 12554.4 12570.2 12595.5 12597.4 12597.8 19914.8 20318.0 20319.5 20326.6 20384.9 8185.8 8338.3 8342.0 8377.7 8397.9 1014.1 1045.6 1055.3 1097.8 1152.1 28879.0 29691.6 30279.7 30428.7 30479.7 1129.3 1149.2 1170.0 1212.2 1225.9 13895.7 15829.2 15945.8 16275.1 16421.8 339267.0 339493.0 339762.0 340131.0 341026.0 55693.4 55829.7 55903.7 55907.4 55918.4 1450.6 1489.8 1513.9 1521.9 1530.8 29720.6 29768.6 30035.5 30078.5 30219.2 45199.8 45299.3 45510.5 45593.3 45658.8 208172.0 209194.0 210079.0 211033.0 211328.0 30377.0 30809.2 31336.5 34483.7 34501.8 39784.0 39827.4 39868.4 40731.8 41173.8 34532.3 34688.5 34842.3 34992.7 35285.0 37453.3 38864.4 39265.8 42574.4 42754.7 37446.0 37499.6 37746.4 37777.1 38860.2 31240.5 31260.4 31319.7 31323.5 32163.0 24391.6 24938.8 24999.8 25161.6 26216.3 10:07:19 INFO - 2016-01-27 10:07:19,618 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,619 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31883.34 Median:2143.64 stddev:103159.86 (4812.4%) stddev-sans-first:103422.10 10:07:19 INFO - 2016-01-27 10:07:19,620 DEBUG : BROWSER_OUTPUT: Values: 368.2 371.3 372.4 372.5 373.8 15201.9 16755.2 17250.7 17587.0 17604.5 12229.7 12484.3 12503.3 13792.3 17958.8 3849.2 5133.2 5189.2 5244.9 5246.7 4012.9 5513.0 5628.5 5651.1 5655.8 5537.8 5578.1 5620.2 5623.8 6053.8 6076.5 6105.9 6112.7 6114.9 6116.8 5245.0 5399.0 5472.7 5475.5 5552.6 14024.6 14146.3 14150.0 20009.0 22713.4 2067.8 2132.6 2154.7 2161.5 2259.9 9854.1 10001.5 11103.7 11985.9 13182.5 1638.1 1719.9 1756.9 1774.9 1804.8 67953.0 71839.0 72279.6 73428.6 73562.6 10623.6 10899.3 11038.6 11060.0 11092.1 14587.9 14651.6 14681.9 14699.5 14708.6 7789.5 7853.3 7858.5 7924.6 7962.0 7320.5 7331.9 7366.0 7401.4 7421.7 4268.8 4669.3 4710.4 4720.8 4750.6 1814.2 1850.5 1911.5 1932.6 1934.0 454.1 464.9 475.8 970.4 1063.7 10347.7 10711.4 10785.9 10957.4 11126.6 483.9 486.0 932.3 939.5 975.3 8649.4 9023.1 9086.6 9246.1 9471.7 619627.0 619863.0 623402.0 644062.0 645334.0 83463.6 83861.2 85726.0 88410.0 88685.0 1216.5 1219.9 1220.1 1226.7 1239.5 14942.0 14982.7 15126.5 15145.8 15275.5 33877.8 34273.1 34752.5 35048.5 35879.5 51727.0 52760.0 53361.7 54597.5 54805.5 10309.2 10344.5 10518.2 10601.4 10622.5 12315.4 12428.9 17616.5 18158.1 18337.5 14606.5 14706.1 14790.5 14900.8 15688.7 15184.5 15374.8 15513.1 15840.4 15898.4 12750.0 13863.0 14537.6 14678.0 15664.5 10302.4 10321.1 10324.4 10384.9 10398.8 11126.8 11924.6 15985.7 16096.0 16146.7 10:07:19 INFO - 2016-01-27 10:07:19,620 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,620 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5397.98 Median:6561.39 stddev:2757.64 (42.0%) stddev-sans-first:2750.29 10:07:19 INFO - 2016-01-27 10:07:19,622 DEBUG : BROWSER_OUTPUT: Values: 1559.9 1561.0 1754.6 1779.7 1814.6 7174.4 7388.1 7390.2 7393.5 7398.2 6729.8 6899.5 7174.7 7474.8 7643.4 2188.9 2224.2 2245.0 2245.1 2247.4 2024.7 2260.7 2261.3 2363.2 2371.7 1987.2 2159.9 2231.8 2233.2 2233.3 2235.6 2254.2 2258.6 2287.6 2288.3 2274.8 2278.2 2298.9 2300.9 2301.3 7603.7 7700.4 7715.3 7717.0 7717.3 4535.0 4664.3 4674.3 4677.9 4678.8 2933.4 2979.4 3022.3 3032.2 3077.1 932.0 936.0 943.4 953.9 972.7 10361.9 10376.4 10492.7 10502.3 10504.2 4083.7 4094.3 4134.5 4185.1 4188.1 9008.9 9009.2 9013.9 9023.0 9025.5 4702.9 4709.1 4733.6 4739.4 4744.5 4307.6 4342.0 4349.5 4351.9 4352.2 3662.9 3694.9 3712.5 3719.8 3737.6 1095.6 1117.3 1133.7 1145.4 1147.7 1000.9 1031.2 1164.1 1234.8 1369.6 7099.1 7146.3 7186.2 7200.6 7215.3 1167.7 1172.4 1174.2 1180.2 1181.6 5721.3 5777.3 5798.6 5802.2 5805.6 8759.5 8879.5 9104.6 9112.6 9113.0 8884.2 8982.7 9010.4 9016.2 9026.8 7235.4 7254.4 7355.9 7366.8 7377.9 7392.1 7442.4 7459.1 7462.5 7473.0 8060.9 8149.3 8369.9 8382.2 8401.9 8280.4 8412.2 8506.7 8529.7 8561.5 6672.2 6924.3 6928.8 6930.5 6932.3 7539.3 7558.3 7570.4 7575.2 7599.8 6184.0 7038.6 7234.5 7305.1 7330.9 6157.3 6600.4 6994.8 7094.3 7106.4 6889.0 6901.9 6910.0 6922.6 6926.8 6470.6 6530.8 6539.8 6557.3 6565.4 6600.2 6639.8 6644.2 6650.9 6654.0 10:07:19 INFO - 2016-01-27 10:07:19,622 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,622 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5538.71 Median:6381.43 stddev:1656.80 (26.0%) stddev-sans-first:1614.67 10:07:19 INFO - 2016-01-27 10:07:19,623 DEBUG : BROWSER_OUTPUT: Values: 330.5 337.6 341.5 342.4 350.4 6178.3 6234.8 6251.7 6285.7 6290.5 6138.1 6174.4 6177.9 6183.1 6186.3 4702.7 4782.5 4788.9 4794.2 4796.5 4764.1 4771.7 4787.3 4792.7 4794.3 4728.8 4729.1 4739.9 4749.9 4754.4 4548.4 4620.5 4624.8 4744.9 4755.0 4141.3 4176.7 4203.2 4204.2 4205.9 5845.5 5849.0 5858.9 5860.2 5879.0 5298.4 5388.3 5466.8 5550.6 5566.5 6499.0 6523.4 6531.7 6532.2 6540.4 4658.3 4661.7 4662.6 4668.3 4669.8 6627.3 6769.6 6790.7 6805.4 6807.6 4792.3 4795.9 4803.4 4804.7 4806.4 4848.9 4864.9 4870.5 4871.8 4871.8 5553.0 5554.9 5560.3 5566.1 5568.0 6450.6 6585.4 6604.6 6605.0 6614.3 1691.6 1717.7 1717.8 1718.4 1718.5 1149.5 1152.8 1166.9 1173.0 1174.2 6459.9 6464.0 6470.3 6475.6 6478.6 6816.6 6818.3 6820.6 6822.1 6836.4 6363.8 6425.3 6444.6 6455.5 6459.5 3669.8 3694.3 3696.8 3696.9 3701.5 7009.3 7014.7 7017.1 7020.3 7020.7 6679.9 6754.9 6945.3 6951.7 6957.9 6416.8 7064.0 7066.4 7080.9 7102.6 6870.5 6882.5 6887.3 6887.6 6892.3 6715.1 6763.1 6764.6 6767.9 6769.1 7119.2 7119.4 7130.1 7131.6 7136.7 4644.3 4688.3 4690.9 4691.3 4693.4 6889.4 6895.1 6900.5 6906.4 6911.2 6399.1 6683.5 6696.1 6696.3 6696.7 6521.8 6523.0 6524.3 6529.4 6536.6 6455.1 6550.4 6578.5 6605.6 6605.8 6573.9 6697.3 6722.3 6730.9 6735.8 6495.2 6560.3 6572.0 6582.0 6584.7 10:07:19 INFO - 2016-01-27 10:07:19,623 DEBUG : BROWSER_OUTPUT: 10:07:19 INFO - 2016-01-27 10:07:19,624 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14243.78 Median:24926.98 stddev:16847.95 (67.6%) stddev-sans-first:16865.01 10:07:19 INFO - 2016-01-27 10:07:19,625 DEBUG : BROWSER_OUTPUT: Values: 810.4 859.1 863.5 880.4 912.5 12728.0 13107.1 13208.3 13223.9 13252.1 13270.5 13364.8 13365.6 13403.0 13405.7 722.4 724.4 877.5 890.4 899.1 892.7 899.9 907.8 907.9 908.8 757.3 925.9 935.2 937.0 937.5 757.5 926.5 931.1 932.0 933.0 687.7 688.2 688.4 691.0 692.1 11707.8 11811.4 11905.6 11926.5 11933.0 11874.3 11956.1 12945.6 13049.3 13152.7 10204.4 10348.8 10356.9 10495.2 10546.5 3468.8 3494.5 3497.9 3502.4 3510.8 47766.8 49858.0 50008.5 50106.2 50168.5 9333.8 9388.0 9425.6 9452.0 9488.7 18986.5 19067.7 19090.6 19120.7 19132.7 7400.4 7451.1 7453.8 7508.7 7700.0 4218.1 4263.2 4290.5 4306.4 4331.6 3030.1 3055.3 3063.9 3085.2 3158.9 530.3 542.9 546.2 563.9 568.0 630.9 643.9 654.3 671.6 683.6 8577.1 9153.5 9199.1 9306.3 9551.1 491.7 613.3 686.1 694.0 719.1 3845.3 4028.7 4118.5 4158.9 4180.9 56000.7 58492.0 58809.0 61539.7 61782.7 53992.7 54736.2 54966.2 55654.0 55828.4 48000.0 48497.3 49362.3 49451.5 50360.5 8591.8 8809.8 8858.2 8897.2 8921.4 34916.7 38728.6 38791.2 38874.2 40827.8 46179.3 46892.3 47052.5 47076.0 47664.8 10969.3 11107.1 11268.9 11405.9 11520.6 11918.6 12021.7 12115.3 12191.0 12440.4 11920.6 12093.5 12158.9 12461.0 12669.6 11269.2 11618.1 11644.3 11679.7 11925.3 10740.6 11057.4 11200.2 11408.3 11438.6 8527.0 8606.9 8681.4 8867.7 8911.9 9471.0 9989.9 9989.9 10111.8 10458.2 10:07:19 INFO - 2016-01-27 10:07:19,625 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:07:19 INFO - 2016-01-27 10:07:19,625 DEBUG : BROWSER_OUTPUT: 10:07:20 INFO - 2016-01-27 10:07:20,152 INFO : Browser exited with error code: 0 10:07:20 INFO - 2016-01-27 10:07:20,171 INFO : Completed test dromaeo_css (00:18:29) 10:07:20 INFO - 2016-01-27 10:07:20,171 INFO : Starting test kraken 10:07:20 INFO - 2016-01-27 10:07:20,171 DEBUG : operating with platform_type : linux_ 10:07:20 INFO - 2016-01-27 10:07:20,171 INFO : Initialising browser for kraken test... 10:07:20 INFO - 2016-01-27 10:07:20,177 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp88fTxh/profile http://localhost:50773/getInfo.html 10:07:24 INFO - 2016-01-27 10:07:24,384 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:07:24 INFO - 2016-01-27 10:07:24,384 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:07:24 INFO - 2016-01-27 10:07:24,384 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:07:24 INFO - 2016-01-27 10:07:24,384 DEBUG : BROWSER_OUTPUT: __metrics 10:07:24 INFO - 2016-01-27 10:07:24,541 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] 10:07:25 INFO - 2016-01-27 10:07:25,189 INFO : Browser initialized. 10:07:25 INFO - 2016-01-27 10:07:25,190 INFO : Running cycle 1/1 for kraken test... 10:07:25 INFO - 2016-01-27 10:07:25,190 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp88fTxh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:07:37 INFO - 2016-01-27 10:07:36,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 134660096 10:07:37 INFO - 2016-01-27 10:07:37,000 DEBUG : BROWSER_OUTPUT: 10:07:39 INFO - 2016-01-27 10:07:39,454 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50773/tests/kraken/driver.html?testName=audio-beat-detection) 10:07:40 INFO - 2016-01-27 10:07:40,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 167972864 10:07:40 INFO - 2016-01-27 10:07:40,028 DEBUG : BROWSER_OUTPUT: 10:07:42 INFO - 2016-01-27 10:07:42,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50773/tests/kraken/driver.html?testName=audio-dft) 10:07:43 INFO - 2016-01-27 10:07:43,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 164241408 10:07:43 INFO - 2016-01-27 10:07:43,166 DEBUG : BROWSER_OUTPUT: 10:07:45 INFO - 2016-01-27 10:07:45,935 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50773/tests/kraken/driver.html?testName=audio-fft) 10:07:46 INFO - 2016-01-27 10:07:46,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 161406976 10:07:46 INFO - 2016-01-27 10:07:46,496 DEBUG : BROWSER_OUTPUT: 10:07:48 INFO - 2016-01-27 10:07:48,494 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50773/tests/kraken/driver.html?testName=audio-oscillator) 10:07:49 INFO - 2016-01-27 10:07:49,060 DEBUG : BROWSER_OUTPUT: RSS: Main: 160575488 10:07:49 INFO - 2016-01-27 10:07:49,060 DEBUG : BROWSER_OUTPUT: 10:07:51 INFO - 2016-01-27 10:07:51,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50773/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:51 INFO - 2016-01-27 10:07:51,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 161034240 10:07:51 INFO - 2016-01-27 10:07:51,835 DEBUG : BROWSER_OUTPUT: 10:07:54 INFO - 2016-01-27 10:07:54,677 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50773/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:55 INFO - 2016-01-27 10:07:55,244 DEBUG : BROWSER_OUTPUT: RSS: Main: 160407552 10:07:55 INFO - 2016-01-27 10:07:55,244 DEBUG : BROWSER_OUTPUT: 10:07:58 INFO - 2016-01-27 10:07:58,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50773/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:59 INFO - 2016-01-27 10:07:59,135 DEBUG : BROWSER_OUTPUT: RSS: Main: 162050048 10:07:59 INFO - 2016-01-27 10:07:59,135 DEBUG : BROWSER_OUTPUT: 10:08:01 INFO - 2016-01-27 10:08:01,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50773/tests/kraken/driver.html?testName=json-parse-financial) 10:08:02 INFO - 2016-01-27 10:08:02,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 160976896 10:08:02 INFO - 2016-01-27 10:08:02,402 DEBUG : BROWSER_OUTPUT: 10:08:03 INFO - 2016-01-27 10:08:03,950 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50773/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:04 INFO - 2016-01-27 10:08:04,503 DEBUG : BROWSER_OUTPUT: RSS: Main: 169037824 10:08:04 INFO - 2016-01-27 10:08:04,503 DEBUG : BROWSER_OUTPUT: 10:08:06 INFO - 2016-01-27 10:08:06,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:06 INFO - 2016-01-27 10:08:06,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 165203968 10:08:06 INFO - 2016-01-27 10:08:06,863 DEBUG : BROWSER_OUTPUT: 10:08:08 INFO - 2016-01-27 10:08:08,827 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:09 INFO - 2016-01-27 10:08:09,396 DEBUG : BROWSER_OUTPUT: RSS: Main: 171724800 10:08:09 INFO - 2016-01-27 10:08:09,396 DEBUG : BROWSER_OUTPUT: 10:08:11 INFO - 2016-01-27 10:08:11,734 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:12 INFO - 2016-01-27 10:08:12,317 DEBUG : BROWSER_OUTPUT: RSS: Main: 181051392 10:08:12 INFO - 2016-01-27 10:08:12,317 DEBUG : BROWSER_OUTPUT: 10:08:14 INFO - 2016-01-27 10:08:14,833 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:15 INFO - 2016-01-27 10:08:15,396 DEBUG : BROWSER_OUTPUT: RSS: Main: 178155520 10:08:15 INFO - 2016-01-27 10:08:15,396 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50773/tests/kraken/driver.html?testName=ai-astar) 10:08:17 INFO - 2016-01-27 10:08:17,279 DEBUG : BROWSER_OUTPUT: RSS: Main: 237068288 10:08:17 INFO - 2016-01-27 10:08:17,280 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,281 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:08:17 INFO - 2016-01-27 10:08:17,281 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:08:17 INFO - 2016-01-27 10:08:17,281 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:08:17 INFO - 2016-01-27 10:08:17,282 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:08:17 INFO - 2016-01-27 10:08:17,282 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;105;109;111;112;101;112;110;113 10:08:17 INFO - 2016-01-27 10:08:17,282 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;120;147;164;123;147;148;123;170;122 10:08:17 INFO - 2016-01-27 10:08:17,283 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;159;159;160;162;161;160;160 10:08:17 INFO - 2016-01-27 10:08:17,283 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;73;144;73;73;70;73;160;72 10:08:17 INFO - 2016-01-27 10:08:17,283 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;146;93;92;92;144;93;93;144 10:08:17 INFO - 2016-01-27 10:08:17,283 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;108;110;109;109;110;109 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;145;145;145;145;145;146 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;94;96;95;95;95 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;55;55;57;56;56;57;57;55;53 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;64;64;65;64;64;64;64 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;75;78;78;79;78;76;77;77;78;76 10:08:17 INFO - 2016-01-27 10:08:17,284 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;119;110;115;110;112;114;121;114;114 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;153;146;147;152;138;150;149;140;143 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;68;65;65;64;66;64;64;66;64 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,873 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: __startTimestamp1453918097280__endTimestamp 10:08:17 INFO - 2016-01-27 10:08:17,285 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.20 Median:110.50 stddev:3.65 (3.3%) stddev-sans-first:3.87 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 105.0 109.0 111.0 112.0 101.0 112.0 110.0 113.0 10:08:17 INFO - 2016-01-27 10:08:17,286 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,287 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.00 stddev:18.19 (12.4%) stddev-sans-first:19.16 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: Values: 147.0 120.0 147.0 164.0 123.0 147.0 148.0 123.0 170.0 122.0 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 159.0 159.0 160.0 162.0 161.0 160.0 160.0 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,288 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.90 Median:72.50 stddev:34.01 (46.9%) stddev-sans-first:35.52 10:08:17 INFO - 2016-01-27 10:08:17,289 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 73.0 144.0 73.0 73.0 70.0 73.0 160.0 72.0 10:08:17 INFO - 2016-01-27 10:08:17,289 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,289 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.30 Median:93.00 stddev:25.10 (27.0%) stddev-sans-first:26.01 10:08:17 INFO - 2016-01-27 10:08:17,289 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 146.0 93.0 92.0 92.0 144.0 93.0 93.0 144.0 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 108.0 110.0 109.0 109.0 110.0 109.0 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 145.0 146.0 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 10:08:17 INFO - 2016-01-27 10:08:17,290 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 95.0 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.25 (2.2%) stddev-sans-first:1.32 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: Values: 56.0 55.0 55.0 57.0 56.0 56.0 57.0 57.0 55.0 53.0 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:78.00 stddev:1.23 (1.6%) stddev-sans-first:1.01 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: Values: 75.0 78.0 78.0 79.0 78.0 76.0 77.0 77.0 78.0 76.0 10:08:17 INFO - 2016-01-27 10:08:17,291 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:114.50 stddev:3.50 (3.1%) stddev-sans-first:3.71 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: Values: 115.0 119.0 110.0 115.0 110.0 112.0 114.0 121.0 114.0 114.0 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.30 Median:148.00 stddev:4.95 (3.3%) stddev-sans-first:5.22 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: Values: 145.0 153.0 146.0 147.0 152.0 138.0 150.0 149.0 140.0 143.0 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.36 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: Values: 65.0 68.0 65.0 65.0 64.0 66.0 64.0 64.0 66.0 64.0 10:08:17 INFO - 2016-01-27 10:08:17,292 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:08:17 INFO - 2016-01-27 10:08:17,293 DEBUG : BROWSER_OUTPUT: 10:08:17 INFO - 2016-01-27 10:08:17,920 INFO : Browser exited with error code: 0 10:08:17 INFO - 2016-01-27 10:08:17,971 INFO : Completed test kraken (00:00:57) 10:08:17 INFO - 2016-01-27 10:08:17,971 INFO : Starting test v8_7 10:08:17 INFO - 2016-01-27 10:08:17,971 DEBUG : operating with platform_type : linux_ 10:08:17 INFO - 2016-01-27 10:08:17,971 INFO : Initialising browser for v8_7 test... 10:08:17 INFO - 2016-01-27 10:08:17,977 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZH3nej/profile http://localhost:50773/getInfo.html 10:08:22 INFO - 2016-01-27 10:08:22,163 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:08:22 INFO - 2016-01-27 10:08:22,163 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:08:22 INFO - 2016-01-27 10:08:22,163 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:08:22 INFO - 2016-01-27 10:08:22,163 DEBUG : BROWSER_OUTPUT: __metrics 10:08:22 INFO - 2016-01-27 10:08:22,320 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] 10:08:23 INFO - 2016-01-27 10:08:23,196 INFO : Browser initialized. 10:08:23 INFO - 2016-01-27 10:08:23,197 INFO : Running cycle 1/1 for v8_7 test... 10:08:23 INFO - 2016-01-27 10:08:23,197 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZH3nej/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:08:34 INFO - 2016-01-27 10:08:34,872 DEBUG : BROWSER_OUTPUT: RSS: Main: 134885376 10:08:34 INFO - 2016-01-27 10:08:34,872 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,218 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50773/tests/v8_7/run.html (next: http://localhost:50773/tests/v8_7/run.html) 10:08:56 INFO - 2016-01-27 10:08:56,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 424333312 10:08:56 INFO - 2016-01-27 10:08:56,468 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,468 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:08:56 INFO - 2016-01-27 10:08:56,468 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: |0;Richards;171.29153819801303 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.82610845557937 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.55153203342616 10:08:56 INFO - 2016-01-27 10:08:56,469 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |4;RayTrace;964.3201542912246 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |5;Earley;542.2993492407809 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |6;Boyer;10578.947368421053 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |7;RegExp;24487.80487804878 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |8;Splay;524.3838489774515 10:08:56 INFO - 2016-01-27 10:08:56,470 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: __startTimestamp1453918136466__endTimestamp 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:08:56 INFO - 2016-01-27 10:08:56,471 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.29 Median:171.29 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: Values: 171.3 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.83 Median:164.83 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: Values: 164.8 10:08:56 INFO - 2016-01-27 10:08:56,472 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.55 Median:278.55 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: Values: 278.6 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: Values: 5170.1 10:08:56 INFO - 2016-01-27 10:08:56,473 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:964.32 Median:964.32 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: Values: 964.3 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.30 Median:542.30 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: Values: 542.3 10:08:56 INFO - 2016-01-27 10:08:56,474 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10578.95 Median:10578.95 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: Values: 10578.9 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24487.80 Median:24487.80 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: Values: 24487.8 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,475 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:524.38 Median:524.38 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: Values: 524.4 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: Values: 4835.7 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:08:56 INFO - 2016-01-27 10:08:56,476 DEBUG : BROWSER_OUTPUT: 10:08:57 INFO - 2016-01-27 10:08:57,120 INFO : Browser exited with error code: 0 10:08:57 INFO - 2016-01-27 10:08:57,137 INFO : Completed test v8_7 (00:00:39) 10:08:57 INFO - 2016-01-27 10:08:57,528 INFO : Completed test suite (00:20:07) 10:08:57 INFO - 2016-01-27 10:08:57,528 DEBUG : Working with test: dromaeo_css 10:08:57 INFO - 2016-01-27 10:08:57,529 DEBUG : Generating results file: dromaeo_css 10:08:57 INFO - 2016-01-27 10:08:57,530 DEBUG : Working with test: kraken 10:08:57 INFO - 2016-01-27 10:08:57,530 DEBUG : Generating results file: kraken 10:08:57 INFO - 2016-01-27 10:08:57,530 INFO : javascript benchmark 10:08:57 INFO - 2016-01-27 10:08:57,530 DEBUG : Working with test: v8_7 10:08:57 INFO - 2016-01-27 10:08:57,530 DEBUG : Generating results file: v8_7 10:08:57 INFO - 2016-01-27 10:08:57,531 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:57 INFO - 2016-01-27 10:08:57,584 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:08:57 INFO - 2016-01-27 10:08:57,584 DEBUG : process_Request line: dromaeo_css 7168.27 graph.html#tests=[[72,53,35]] 10:08:57 INFO - 2016-01-27 10:08:57,584 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:57 INFO - 2016-01-27 10:08:57,617 DEBUG : process_Request line: kraken 1478.70 graph.html#tests=[[232,53,35]] 10:08:57 INFO - 2016-01-27 10:08:57,617 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:08:57 INFO - 2016-01-27 10:08:57,648 DEBUG : process_Request line: v8_7 23168.28 graph.html#tests=[[230,53,35]] 10:08:57 INFO - 2016-01-27 10:08:57,650 INFO : javascript benchmark 10:08:57 INFO - 2016-01-27 10:08:57,652 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3187.1713354527824, 3292.226326813197, 3322.2192784695594, 3325.6508066653, 3344.26426186239, 5472.753726082326, 5528.0, 5547.528460080898, 5561.666300022001, 5576.721163941807, 5542.75057622407, 5558.416366281543, 5559.555235581154, 5578.581606379527, 5588.30146231721, 4939.407271127469, 5041.243813427996, 5056.342675452186, 5075.568576670988, 5171.7414129293575, 4948.38145231846, 4997.100521906055, 5026.9946010797985, 5056.367954005749, 5096.923546146811, 4983.0, 5049.0, 5057.393112826466, 5068.163752980754, 5114.590832733373, 5154.355705536808, 5174.353205849269, 5182.689038657692, 5192.299039629677, 5221.2951251580935, 4912.778924948386, 4925.482824303433, 5041.445441001487, 5086.109930762139, 5090.032893750175, 5300.734963251861, 5302.840914772563, 5308.893822123575, 5324.361076670785, 5362.490563396471, 5858.941410585925, 5864.384239654817, 5905.852353691149, 5920.97039514804, 5922.31893332268, 5912.3496415394275, 5946.0, 5972.283326000865, 6032.577719559633, 6053.092036194562, 5606.719664016824, 5628.04323265046, 5629.690367029812, 5636.436356364331, 5640.971795141039, 6029.638221706711, 6032.517398608188, 6038.426349496791, 6038.63768173911, 6038.758449662052, 5640.746166422521, 5745.2818397700285, 5795.9420405796245, 5893.823185304362, 5926.170336152942, 5866.73599688015, 5966.134910437962, 5975.223220981245, 5975.372585878507, 5979.5515336349945, 5646.661200327993, 5935.228420305332, 5949.434803693643, 5952.791652292236, 5956.493698035629, 5787.421257874263, 5787.623804452697, 5900.409959004065, 5906.940930590639, 5934.465898069108, 4539.137563862855, 4560.657950653715, 4561.429821272341, 4576.885577860514, 4592.265237561974, 4055.7566546007333, 4081.8775436736946, 4104.0, 4126.587341265909, 4137.834486620502, 5577.1355439906065, 5659.490645841894, 5680.914786278209, 5711.200431939532, 5711.8000869969355, 6024.277086749618, 6028.879422411527, 6036.667983260875, 6039.54703397247, 6043.0, 5427.294451721251, 5546.306711661043, 5686.914696279559, 5695.345035320908, 5696.11710184922, 5129.64092513528, 5207.270982062514, 5207.505286997729, 5214.0614689355525, 5240.4759524047295, 6169.9383006170265, 6179.351168127372, 6210.658413787285, 6213.844653883689, 6218.906716399258, 5792.797252096157, 5810.76756929735, 5966.254218222723, 6075.27096748393, 6089.299730530868, 6179.134921111138, 6203.348648392034, 6205.844853878689, 6211.13044173807, 6216.129741836056, 5927.911081333869, 5966.612170208922, 5983.37174596661, 5985.042393217064, 6027.698615069141, 5778.8844223114465, 5945.197398351167, 5947.464728174485, 5991.101334799815, 6117.021276595723, 5834.591578589458, 5836.445537673914, 5841.474267315962, 5844.824655260349, 5847.356790753099, 5841.211436456024, 5854.0, 5861.706914654165, 5864.06175011996, 5877.265341832271, 5508.0, 5583.609147359808, 5610.663360198401, 5676.545876329801, 5718.027935250934, 5824.126381042708, 5825.533957283492, 5828.184054232495, 5832.883342333214, 5842.503387212135, 5488.4237155098135, 5669.34802497718, 5699.430056994267, 5728.054870946247, 5733.5126514245085], "unit": "ms", "name": "dojo.html", "value": 5486.113949750266}, {"lowerIsBetter": false, "replicates": [601.9969900150498, 615.2094558492338, 633.4805459523191, 645.1935081148563, 709.0250905005618, 30321.696783032192, 30883.22791930203, 40280.1943961121, 40892.18215635685, 41008.38487422691, 45993.31010034844, 46470.070598588005, 46477.30284045742, 46485.30272045922, 46587.76706116473, 8016.799580010488, 8157.0, 8969.013408525074, 9178.816423671522, 9185.402948808307, 10863.891361086367, 11071.833922491169, 11100.056495197878, 11142.387168705718, 11143.052840508566, 10839.945800270987, 10930.672079837617, 10948.233623646347, 10948.835767463494, 10959.452027398638, 10798.190135739811, 10914.181436392262, 10915.454227288605, 10920.890791092106, 10928.726781830439, 10690.358578485315, 10862.891371086267, 10869.782604347947, 10875.184361172944, 10882.29265097774, 41557.0, 42098.0, 42128.0, 42170.78914605438, 42249.0, 3625.546806649169, 4089.9591004089875, 4278.187144442546, 4285.35719642053, 4415.779211039435, 31664.208394790083, 31985.52021719676, 32043.0, 32196.034118976233, 32200.677993220237, 3561.7506774525796, 3602.9819850900576, 3695.297893400272, 3696.741228114033, 3761.022134245116, 294434.0, 294534.0, 294758.0, 295226.0, 296968.0, 54737.72631136819, 55826.0, 62172.37827621756, 62443.375566243754, 62672.68663656653, 55560.44439555633, 55562.72218638962, 55643.44356556464, 55741.16388254098, 56091.71954140203, 16290.674186516204, 16501.669966600602, 16504.83495165057, 16538.6692266154, 16654.916725416297, 12554.372281386077, 12570.182938108994, 12595.496180152875, 12597.370131493388, 12597.811032834516, 19914.800851991295, 20318.0, 20319.4920126998, 20326.593468130555, 20384.89807550953, 8185.813057106686, 8338.291245244221, 8341.95829020851, 8377.748667539983, 8397.874031889527, 1014.0974532665933, 1045.6174276216586, 1055.2718624149313, 1097.7969075721016, 1152.147410915933, 28879.0, 29691.554626680616, 30279.69720302813, 30428.695713043027, 30479.695203047686, 1129.3224065560598, 1149.2357582207737, 1169.9649010529697, 1212.1878341511208, 1225.8528976522875, 13895.722085558435, 15829.208539572975, 15945.760813587805, 16275.10486923207, 16421.83578164227, 339267.0, 339493.0, 339762.0, 340131.0, 341026.0, 55693.443065569634, 55829.7208513963, 55903.72048139733, 55907.440925591036, 55918.440815592134, 1450.55032939789, 1489.7616381378928, 1513.902420744951, 1521.9391224351123, 1530.8163020437619, 29720.554191686708, 29768.605378360753, 30035.549466758453, 30078.54882176813, 30219.244518887204, 45199.77400112979, 45299.320510193036, 45510.54489455195, 45593.31610025919, 45658.771706141255, 208172.0, 209194.0, 210079.0, 211033.0, 211328.0, 30377.0, 30809.229769255053, 31336.52995205028, 34483.65516344804, 34501.827490862386, 39784.0, 39827.40258896061, 39868.401973969834, 40731.77804665865, 41173.79413102915, 34532.30935381228, 34688.479672804424, 34842.30315393729, 34992.65007349894, 35285.0, 37453.25093498169, 38864.41703374395, 39265.80367098146, 42574.361384579875, 42754.727349935805, 37446.0, 37499.625003749614, 37746.43380349352, 37777.05557361088, 38860.22279554336, 31240.531392028683, 31260.374792503568, 31319.68680313259, 31323.530147047357, 32163.0, 24391.634125487777, 24938.75061249437, 24999.750002499743, 25161.622575661746, 26216.34459138537], "unit": "ms", "name": "ext.html", "value": 19449.001384350846}, {"lowerIsBetter": false, "replicates": [368.1954928481269, 371.3371631637527, 372.3799873211104, 372.474810517171, 373.771999080561, 15201.935864489462, 16755.246013929373, 17250.65498690027, 17587.0, 17604.471865844014, 12229.694257643563, 12484.313362765068, 12503.31231782254, 13792.310384480761, 17958.820411795878, 3849.2301539692035, 5133.1786914093755, 5189.221616757479, 5244.947550524503, 5246.711430871301, 4012.899677508071, 5512.972435137839, 5628.549716022729, 5651.124075768242, 5655.802046928358, 5537.750801213955, 5578.135389014709, 5620.185073164389, 5623.80316688916, 6053.818385448443, 6076.544259180557, 6105.938940610581, 6112.724927378256, 6114.908276375858, 6116.847078823021, 5245.0, 5398.974194902956, 5472.671639701591, 5475.479829416199, 5552.555795536347, 14024.649383765385, 14146.29268536569, 14150.0, 20009.0, 22713.432164195863, 2067.7622073461594, 2132.605467988427, 2154.666026765854, 2161.459635091227, 2259.8870056497276, 9854.113129818279, 10001.499925003793, 11103.72240693981, 11985.880141198564, 13182.472701091945, 1638.1236038719228, 1719.9484015479431, 1756.9472915812546, 1774.8935063896208, 1804.8195180481978, 67953.0, 71839.0, 72279.63860180718, 73428.6328568359, 73562.63218683926, 10623.575056997788, 10899.346039237671, 11038.613648522263, 11060.0, 11092.057175140042, 14587.854121458862, 14651.560453186421, 14681.92659036698, 14699.485518007032, 14708.632284192767, 7789.493682910593, 7853.332466740277, 7858.489198202213, 7924.6037698116015, 7961.960190199012, 7320.487565870444, 7331.853362932711, 7366.042414486083, 7401.444891633041, 7421.666025028887, 4268.786560671954, 4669.2996050592, 4710.36410084641, 4720.787564559603, 4750.643701722384, 1814.1926842555201, 1850.463365623954, 1911.4934542346289, 1932.603816217672, 1933.951651208731, 454.14393867559886, 464.8721601559598, 475.80016393114977, 970.4080510888352, 1063.7021633942509, 10347.689569312932, 10711.410872401937, 10785.946070269598, 10957.397343146204, 11126.61056863006, 483.8935434204469, 486.03036941302327, 932.2774849491698, 939.5396255834592, 975.317277905469, 8649.394542382099, 9023.142801433853, 9086.636534538544, 9246.121618446237, 9471.715848524555, 619627.0, 619863.0, 623402.0, 644062.0, 645334.0, 83463.5826820862, 83861.16138838533, 85726.0, 88410.0, 88685.0, 1216.4829947272374, 1219.8719134490927, 1220.1337050693928, 1226.6504046346927, 1239.4856134704178, 14942.0, 14982.700345992802, 15126.470573529874, 15145.848541514444, 15275.541733748005, 33877.83061084679, 34273.14317142092, 34752.478712818825, 35048.47427288542, 35879.46180807342, 51727.0, 52760.0, 53361.7331913338, 54597.454025459236, 54805.45194548163, 10309.175265978854, 10344.482775861328, 10518.211134165122, 10601.363918164934, 10622.521986510472, 12315.44580493862, 12428.875711243134, 17616.471505854846, 18158.09209539544, 18337.541561460537, 14606.488772892897, 14706.132304200579, 14790.482333118289, 14900.7764883529, 15688.686226275639, 15184.46854360092, 15374.846251537341, 15513.146776926926, 15840.366385344458, 15898.364065437252, 12750.0, 13863.0, 14537.563873083824, 14678.0, 15664.451744188898, 10302.381857088598, 10321.122704570196, 10324.380537167794, 10384.948075259575, 10398.792024159626, 11126.833097503706, 11924.642260732191, 15985.68028639444, 16096.034237945762, 16146.67706645884], "unit": "ms", "name": "jquery.html", "value": 8879.71184526483}, {"lowerIsBetter": false, "replicates": [1559.8752099832016, 1560.968780624387, 1754.578901063745, 1779.7419374190742, 1814.582645991422, 7174.390176834969, 7388.076490438695, 7390.224026477224, 7393.482456228066, 7398.223186565408, 6729.831754206148, 6899.517033807636, 7174.7130114795345, 7474.7757567273065, 7643.426742994284, 2188.912443502258, 2224.177054489841, 2245.0, 2245.146844199202, 2247.3595025417676, 2024.6659301215354, 2260.7400148982915, 2261.2989973108306, 2363.196513185517, 2371.703537057868, 1987.1554589299562, 2159.8920053997317, 2231.8214542836536, 2233.1737257214854, 2233.2853486884205, 2235.58641651295, 2254.233560589399, 2258.5708715344113, 2287.645414960667, 2288.313505948209, 2274.8293877959054, 2278.1798552521077, 2298.8735619540917, 2300.907963681451, 2301.2635956493928, 7603.695852165907, 7700.422468314899, 7715.344195743369, 7717.0, 7717.263615686482, 4535.0, 4664.300354946785, 4674.252119660872, 4677.906441871178, 4678.812847486097, 2933.369325594986, 2979.3594377208788, 3022.335086281015, 3032.1813110460052, 3077.061496243645, 931.9860202096974, 936.0499093420184, 943.4433684126329, 953.8759961205068, 972.6936015155203, 10361.89638103617, 10376.429296388696, 10492.685219443429, 10502.317349372266, 10504.212184086224, 4083.734557253769, 4094.303968325392, 4134.462519872457, 4185.058361868555, 4188.141431006637, 9008.864867027, 9009.189172974464, 9013.909860901307, 9023.0, 9025.548722563977, 4702.858914232578, 4709.105269998689, 4733.5503127202865, 4739.383880095635, 4744.5492678195515, 4307.633851122627, 4342.0, 4349.543297953669, 4351.912961740811, 4352.194843953878, 3662.9450558241647, 3694.852205911787, 3712.535933008374, 3719.795411252407, 3737.588865224823, 1095.5727266366123, 1117.279354816139, 1133.7109037195462, 1145.410113791398, 1147.7015975846173, 1000.9099181073593, 1031.231732359397, 1164.132721122769, 1234.7592219517287, 1369.6233535777742, 7099.148102227766, 7146.285371462916, 7186.245444228385, 7200.603966781772, 7215.314545118205, 1167.6788883057227, 1172.384498138484, 1174.2308787744041, 1180.226951346869, 1181.6277872470143, 5721.256236689287, 5777.277840269966, 5798.623089499169, 5802.187693722883, 5805.593608447452, 8759.518226497476, 8879.467231966102, 9104.635814567344, 9112.589933453011, 9112.95443522778, 8884.156005179497, 8982.730518084469, 9010.369274150878, 9016.23362014223, 9026.819463610822, 7235.38499227561, 7254.383377412873, 7355.896615507717, 7366.815829604303, 7377.852442951111, 7392.075990501187, 7442.4418168636485, 7459.142198647225, 7462.514936529108, 7472.9626351870065, 8060.919390806252, 8149.2665660088505, 8369.916300837158, 8382.203690649378, 8401.915980840113, 8280.410722762232, 8412.158784121539, 8506.74479765608, 8529.658813647384, 8561.48631082137, 6672.199336079701, 6924.272951340036, 6928.792136235921, 6930.480213984072, 6932.272111428228, 7539.283768042027, 7558.281963213486, 7570.432217583593, 7575.204603516551, 7599.848003039798, 6184.0, 7038.648067596498, 7234.493585448968, 7305.086864141982, 7330.853382932418, 6157.322694503511, 6600.438962688226, 6994.790156295319, 7094.290570942864, 7106.360427561544, 6888.965555172393, 6901.861962760817, 6909.99805028258, 6922.58464492132, 6926.757563485254, 6470.6441145737435, 6530.771423000172, 6539.803805885831, 6557.344265573404, 6565.44193743518, 6600.207975043026, 6639.767608133692, 6644.235912870082, 6650.90023649636, 6654.001899715082], "unit": "ms", "name": "mootools.html", "value": 4452.474078613331}, {"lowerIsBetter": false, "replicates": [330.47289573130877, 337.5645417411539, 341.46901568061656, 342.4144712541554, 350.41305812763613, 6178.28949670788, 6234.812955611334, 6251.656158911258, 6285.748570057192, 6290.49676025918, 6138.079288106786, 6174.35169307223, 6177.9382206177925, 6183.134361189426, 6186.319504854462, 4702.741349225791, 4782.473927867941, 4788.928166077511, 4794.208955522335, 4796.544328288807, 4764.094821983829, 4771.689840160396, 4787.305840653104, 4792.736399498043, 4794.280857871312, 4728.810847566093, 4729.1487532244355, 4739.881502962419, 4749.928751068736, 4754.429468463789, 4548.385967894342, 4620.468646105707, 4624.838130665445, 4744.881377965544, 4755.025219829944, 4141.337386018223, 4176.728512646669, 4203.201391735561, 4204.1801848639525, 4205.873823785291, 5845.503132233765, 5848.97075514624, 5858.882822343572, 5860.208871802337, 5879.0, 5298.443663415363, 5388.299521062276, 5466.808661696821, 5550.555955523549, 5566.4990150886615, 6498.967505162444, 6523.4455071319, 6531.673416329212, 6532.248791389004, 6540.37866402691, 4658.277966915133, 4661.741329840958, 4662.626989840838, 4668.323093151474, 4669.813207471697, 6627.304133066055, 6769.627670478122, 6790.728370865159, 6805.421539169127, 6807.625580593016, 4792.257200133985, 4795.880102997383, 4803.423589169252, 4804.711717296974, 4806.375171227718, 4848.878778030577, 4864.854054378375, 4870.464248932614, 4871.780769865364, 4871.805127794849, 5553.0, 5554.91667624986, 5560.304961879765, 5566.053770858964, 5568.0, 6450.645214513247, 6585.440237579859, 6604.603723776589, 6604.966975165094, 6614.272430032693, 1691.6362981958937, 1717.7165767648198, 1717.8110407855127, 1718.3642052440428, 1718.458685514059, 1149.4654985431814, 1152.7809716154072, 1166.859976802789, 1173.049829637979, 1174.2191442690514, 6459.935400645933, 6464.0, 6470.288268290485, 6475.579087359401, 6478.643674597943, 6816.625085620339, 6818.28408017151, 6820.590764554143, 6822.147231596051, 6836.350546698056, 6363.75746870674, 6425.325340839238, 6444.645544495098, 6455.451286640594, 6459.483241340681, 3669.7798132112157, 3694.2980833641523, 3696.833642486041, 3696.907577310535, 3701.4632878232505, 7009.264027277164, 7014.719411223494, 7017.087778588853, 7020.333068358498, 7020.684069216897, 6679.933200668028, 6754.864902701918, 6945.340192681687, 6951.652417379212, 6957.8956315655305, 6416.839579010469, 7063.964680176669, 7066.364027237573, 7080.90246011869, 7102.573845569385, 6870.484713646397, 6882.518223724292, 6887.311268873073, 6887.621180835102, 6892.345227203407, 6715.093462382712, 6763.086983257394, 6764.594124352555, 6767.864642707217, 6769.052332673528, 7119.181294151239, 7119.430445564446, 7130.073090498202, 7131.643417828984, 7136.7145314186855, 4644.256918892961, 4688.296755486568, 4690.882727931829, 4691.272852707836, 4693.366395536559, 6889.414399776075, 6895.103636527219, 6900.516963812685, 6906.447484201352, 6911.205211400552, 6399.104125422537, 6683.532152749261, 6696.129503164558, 6696.330366963265, 6696.732130714911, 6521.836954075996, 6523.021546767833, 6524.3149469305035, 6529.379708927644, 6536.607803531803, 6455.096286519797, 6550.410463058157, 6578.506612004023, 6605.636689982097, 6605.834854128493, 6573.868522629617, 6697.330266973264, 6722.294159113419, 6730.932690673227, 6735.831604209934, 6495.220573531206, 6560.343965603593, 6571.967140164268, 6581.967090164519, 6584.736610535717], "unit": "ms", "name": "prototype.html", "value": 4999.741476798737}, {"lowerIsBetter": false, "replicates": [810.3841080778608, 859.1408591408596, 863.5250612163309, 880.4497189256715, 912.5209265135803, 12728.045396595258, 13107.148035377717, 13208.273544955024, 13223.933880330596, 13252.13861099028, 13270.469181232764, 13364.799528007063, 13365.599032029055, 13403.0, 13405.731885362311, 722.4220623501178, 724.3625609463664, 877.4647465046332, 890.4034297021011, 899.1008991008991, 892.6830975003877, 899.9415038022507, 907.7503686486203, 907.8774365460645, 908.7546362482126, 757.2843662738715, 925.8518637018045, 935.163029088965, 936.967206147785, 937.5499760115149, 757.5000499670218, 926.4672813132476, 931.0503286647615, 932.049309704098, 932.9626814927428, 687.7352219395568, 688.1810645332089, 688.4389222783416, 690.9516333856632, 692.1348314606741, 11707.824382634268, 11811.350375729331, 11905.642830715093, 11926.522939082426, 11933.0, 11874.346910919896, 11956.103292253028, 12945.611631651065, 13049.282289474075, 13152.6711832204, 10204.438755868425, 10348.844767328495, 10356.948215258875, 10495.160387169008, 10546.525406356732, 3468.757186996911, 3494.52823868777, 3497.9125521862156, 3502.387082260594, 3510.7717998330027, 47766.761166193945, 49858.0, 50008.49991500075, 50106.24840627394, 50168.498315016746, 9333.766655833659, 9388.0, 9425.575849086808, 9451.952740236255, 9488.667896623723, 18986.52533686669, 19067.665263431703, 19090.61818763617, 19120.713189302172, 19132.713009304873, 7400.407967362598, 7451.105867295959, 7453.813654658568, 7508.699652013967, 7699.960505331708, 4218.0720241546815, 4263.16868210696, 4290.485141783006, 4306.375575541591, 4331.631811296075, 3030.10611869499, 3055.3278278778635, 3063.8774449022235, 3085.1515833146063, 3158.873645054218, 530.2947080383082, 542.9077056900336, 546.2216341713043, 563.8787660652979, 567.9943200567941, 630.9432151106422, 643.8744444833212, 654.2639530528156, 671.6406722403491, 683.641088428579, 8577.099404562556, 9153.496557689392, 9199.080091990882, 9306.302027347841, 9551.14039736427, 491.69514900761754, 613.3467856732566, 686.0806519264111, 694.0, 719.133444199738, 3845.288621605012, 4028.71798974069, 4118.485189351331, 4158.93947043502, 4180.937285940713, 56000.719996399755, 58492.0, 58809.0, 61539.6923015391, 61782.69108654518, 53992.73003634957, 54736.17895731567, 54966.17550736742, 55654.0, 55828.44171558314, 48000.0, 48497.272540911916, 49362.259566106535, 49451.50548494469, 50360.49639503558, 8591.828163436821, 8809.779755506164, 8858.202761751474, 8897.154770296811, 8921.420107693239, 34916.65083349134, 38728.6127138725, 38791.22417551577, 38874.22251555009, 40827.7958610205, 46179.3073103897, 46892.29661555011, 47052.52947470482, 47076.0, 47664.761676191396, 10969.341839489656, 11107.05590024857, 11268.887311126784, 11405.885941140814, 11520.596779112691, 11918.642440726791, 12021.699457513632, 12115.273083615011, 12191.0, 12440.440180191732, 11920.642380728592, 12093.516259349879, 12158.878411216128, 12461.0, 12669.619911402673, 11269.211155219384, 11618.0705543558, 11644.301341919512, 11679.708007299885, 11925.344106073902, 10740.563498235442, 11057.447127643747, 11200.159988001096, 11408.258463199865, 11438.599649012245, 8526.957365213135, 8606.913930860612, 8681.435706679045, 8867.68963086289, 8911.910880891368, 9471.0, 9989.850152247867, 9989.850152247867, 10111.797764044532, 10458.215633827342], "unit": "ms", "name": "yui.html", "value": 6431.876277683094}], "name": "dromaeo_css", "value": 7168.270842364765}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 109.0, 111.0, 112.0, 101.0, 112.0, 110.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 109.2}, {"lowerIsBetter": true, "replicates": [147.0, 120.0, 147.0, 164.0, 123.0, 147.0, 148.0, 123.0, 170.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.1}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 159.0, 159.0, 160.0, 162.0, 161.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.0}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 73.0, 144.0, 73.0, 73.0, 70.0, 73.0, 160.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 146.0, 93.0, 92.0, 92.0, 144.0, 93.0, 93.0, 144.0], "unit": "ms", "name": "audio-oscillator", "value": 108.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 108.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.1}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 57.0, 56.0, 56.0, 57.0, 57.0, 55.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [75.0, 78.0, 78.0, 79.0, 78.0, 76.0, 77.0, 77.0, 78.0, 76.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.2}, {"lowerIsBetter": true, "replicates": [115.0, 119.0, 110.0, 115.0, 110.0, 112.0, 114.0, 121.0, 114.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.4}, {"lowerIsBetter": true, "replicates": [145.0, 153.0, 146.0, 147.0, 152.0, 138.0, 150.0, 149.0, 140.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.3}, {"lowerIsBetter": true, "replicates": [65.0, 68.0, 65.0, 65.0, 64.0, 66.0, 64.0, 64.0, 66.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1478.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.29153819801303], "unit": "score", "name": "Richards", "value": 206.09307599999988}, {"lowerIsBetter": false, "replicates": [164.82610845557937], "unit": "score", "name": "DeltaBlue", "value": 401.137906}, {"lowerIsBetter": false, "replicates": [278.55153203342616], "unit": "score", "name": "Encrypt", "value": 955.5897900000004}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [964.3201542912246], "unit": "score", "name": "RayTrace", "value": 767.3685930000003}, {"lowerIsBetter": false, "replicates": [542.2993492407809], "unit": "score", "name": "Earley", "value": 1228.9577720000002}, {"lowerIsBetter": false, "replicates": [10578.947368421053], "unit": "score", "name": "Boyer", "value": 62.99899004975127}, {"lowerIsBetter": false, "replicates": [24487.80487804878], "unit": "score", "name": "RegExp", "value": 37.201578685258944}, {"lowerIsBetter": false, "replicates": [524.3838489774515], "unit": "score", "name": "Splay", "value": 155.40333699999996}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23168.275799251733}]} 10:08:57 INFO - RETURN: dromaeo_css: 7168.27 10:08:57 INFO - RETURN: kraken: 1478.70 10:08:57 INFO - RETURN: v8_7: 23168.28 10:08:57 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23168.28"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1478.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7168.27"}}} 10:08:57 INFO - Return code: 0 10:08:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:08:57 INFO - # TBPL SUCCESS # 10:08:57 INFO - Running post-action listener: _resource_record_post_action 10:08:57 INFO - Running post-run listener: _resource_record_post_run 10:08:58 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 8192; Write bytes: 519045120; Read time: 276; Write time: 460116 10:08:58 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 254590976; Read time: 132; Write time: 332064 10:08:58 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 4096; Write bytes: 264019968; Read time: 144; Write time: 128020 10:08:58 INFO - Running post-run listener: _upload_blobber_files 10:08:58 INFO - Blob upload gear active. 10:08:58 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:08:58 INFO - Copying logs to upload dir... 10:08:58 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.971615 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-27 10:08:58.709351) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-27 10:08:58.714951) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009974 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1453908961/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-01-27 10:08:58.766541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 10:08:58.766873) ========= 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=aa08bb06ff6271ef470a76a4000001be-1453916566.34290-2007110738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004569 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 10:08:58.816253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 10:08:58.816570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 10:08:58.816851) ========= ========= Total master_lag: 0.34 =========