builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1454950901.59 results: success (0) buildid: 20160208063907 builduid: 9c977022273d423496f589190071befa revision: ca3a698f224b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.593629) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.594097) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.594440) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004289 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.614540) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.615133) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.624392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.625217) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003254 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.674887) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.675665) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.676390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.676974) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-08 09:01:42-- 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% 46.6M=0s 2016-02-08 09:01:42 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101466 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.794976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.795737) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021422 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.850995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:41.851800) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ca3a698f224b --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 ca3a698f224b --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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-08 09:01:42,586 Setting DEBUG logging. 2016-02-08 09:01:42,586 attempt 1/10 2016-02-08 09:01:42,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ca3a698f224b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-08 09:01:42,844 unpacking tar archive at: mozilla-beta-ca3a698f224b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.525249 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:42.391056) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:42.391449) ========= echo ca3a698f224b in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'ca3a698f224b'] 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False ca3a698f224b program finished with exit code 0 elapsedTime=0.004698 script_repo_revision: 'ca3a698f224b' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:42.443338) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:42.443681) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:01:42.455464) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-08 09:01:42.456163) ========= /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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:43 INFO - MultiFileLogger online at 20160208 09:01:43 in /builds/slave/test-pgo 09:01:43 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:01:43 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:01:43 INFO - {'append_to_log': False, 09:01:43 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:01:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:01:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:01:43 INFO - 'branch': 'Mozilla-Beta', 09:01:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:43 INFO - 'config_files': ('talos/linux_config.py',), 09:01:43 INFO - 'default_actions': ('clobber', 09:01:43 INFO - 'read-buildbot-config', 09:01:43 INFO - 'download-and-extract', 09:01:43 INFO - 'populate-webroot', 09:01:43 INFO - 'create-virtualenv', 09:01:43 INFO - 'install', 09:01:43 INFO - 'run-tests'), 09:01:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:43 INFO - 'download_minidump_stackwalk': True, 09:01:43 INFO - 'download_symbols': 'ondemand', 09:01:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:43 INFO - 'installer_path': 'installer.exe', 09:01:43 INFO - 'log_level': 'info', 09:01:43 INFO - 'log_name': 'talos', 09:01:43 INFO - 'log_to_console': True, 09:01:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:43 INFO - 'opt_config_files': (), 09:01:43 INFO - 'pip_index': False, 09:01:43 INFO - 'sps_profile': False, 09:01:43 INFO - 'sps_profile_interval': 0, 09:01:43 INFO - 'suite': 'dromaeojs', 09:01:43 INFO - 'system_bits': '32', 09:01:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:43 INFO - 'title': 'talos-linux64-ix-060', 09:01:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:43 INFO - 'use_talos_json': True, 09:01:43 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:01:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:43 INFO - 'work_dir': 'build'} 09:01:43 INFO - ##### 09:01:43 INFO - ##### Running clobber step. 09:01:43 INFO - ##### 09:01:43 INFO - Running pre-action listener: _resource_record_pre_action 09:01:43 INFO - Running main action method: clobber 09:01:43 INFO - rmtree: /builds/slave/test-pgo/build 09:01:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:01:43 INFO - Running post-action listener: _resource_record_post_action 09:01:43 INFO - ##### 09:01:43 INFO - ##### Running read-buildbot-config step. 09:01:43 INFO - ##### 09:01:43 INFO - Running pre-action listener: _resource_record_pre_action 09:01:43 INFO - Running main action method: read_buildbot_config 09:01:43 INFO - Using buildbot properties: 09:01:43 INFO - { 09:01:43 INFO - "properties": { 09:01:43 INFO - "buildnumber": 108, 09:01:43 INFO - "product": "firefox", 09:01:43 INFO - "script_repo_revision": "production", 09:01:43 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:01:43 INFO - "repository": "", 09:01:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:01:43 INFO - "buildid": "20160208063907", 09:01:43 INFO - "slavename": "talos-linux64-ix-060", 09:01:43 INFO - "pgo_build": "True", 09:01:43 INFO - "basedir": "/builds/slave/test-pgo", 09:01:43 INFO - "project": "", 09:01:43 INFO - "platform": "ubuntu64_hw", 09:01:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:01:43 INFO - "slavebuilddir": "test", 09:01:43 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:01:43 INFO - "branch": "mozilla-beta", 09:01:43 INFO - "repo_path": "releases/mozilla-beta", 09:01:43 INFO - "stage_platform": "linux64-pgo", 09:01:43 INFO - "builduid": "9c977022273d423496f589190071befa", 09:01:43 INFO - "revision": "ca3a698f224b" 09:01:43 INFO - }, 09:01:43 INFO - "sourcestamp": { 09:01:43 INFO - "repository": "", 09:01:43 INFO - "hasPatch": false, 09:01:43 INFO - "project": "", 09:01:43 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:01:43 INFO - "changes": [ 09:01:43 INFO - { 09:01:43 INFO - "category": null, 09:01:43 INFO - "files": [ 09:01:43 INFO - { 09:01:43 INFO - "url": null, 09:01:43 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:01:43 INFO - } 09:01:43 INFO - ], 09:01:43 INFO - "repository": "", 09:01:43 INFO - "rev": "ca3a698f224b", 09:01:43 INFO - "who": "sendchange", 09:01:43 INFO - "when": 1454950853, 09:01:43 INFO - "number": 7578953, 09:01:43 INFO - "comments": "Bug 1244480 - don_t throw when passing random value as the second argument to canvas.getContext. r=bz, a=sylvestre", 09:01:43 INFO - "project": "", 09:01:43 INFO - "at": "Mon 08 Feb 2016 09:00:53", 09:01:43 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:01:43 INFO - "revlink": "", 09:01:43 INFO - "properties": [ 09:01:43 INFO - [ 09:01:43 INFO - "buildid", 09:01:43 INFO - "20160208063907", 09:01:43 INFO - "Change" 09:01:43 INFO - ], 09:01:43 INFO - [ 09:01:43 INFO - "builduid", 09:01:43 INFO - "9c977022273d423496f589190071befa", 09:01:43 INFO - "Change" 09:01:43 INFO - ], 09:01:43 INFO - [ 09:01:43 INFO - "pgo_build", 09:01:43 INFO - "True", 09:01:43 INFO - "Change" 09:01:43 INFO - ] 09:01:43 INFO - ], 09:01:43 INFO - "revision": "ca3a698f224b" 09:01:43 INFO - } 09:01:43 INFO - ], 09:01:43 INFO - "revision": "ca3a698f224b" 09:01:43 INFO - } 09:01:43 INFO - } 09:01:43 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:01:43 INFO - Running post-action listener: _resource_record_post_action 09:01:43 INFO - ##### 09:01:43 INFO - ##### Running download-and-extract step. 09:01:43 INFO - ##### 09:01:43 INFO - Running pre-action listener: _resource_record_pre_action 09:01:43 INFO - Running main action method: download_and_extract 09:01:43 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/test_packages.json 09:01:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:01:43 INFO - Downloaded 1270 bytes. 09:01:43 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:01:43 INFO - Using the following test package requirements: 09:01:43 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:01:43 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:01:43 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'jsshell-linux-x86_64.zip'], 09:01:43 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:01:43 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:01:43 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:01:43 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:01:43 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:01:43 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:01:43 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:01:43 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:01:43 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:43 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:01:43 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:44 INFO - Downloaded 21580550 bytes. 09:01:44 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:01:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:01:44 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:01:45 INFO - Return code: 0 09:01:45 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:01:45 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:01:45 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:01:45 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:45 INFO - Downloaded 21580550 bytes. 09:01:45 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:01:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:01:45 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:01:46 INFO - Return code: 0 09:01:46 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:01:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:01:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:47 INFO - Downloaded 11132038 bytes. 09:01:47 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:01:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:01:47 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:01:47 INFO - Return code: 0 09:01:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:01:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/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:01:50 INFO - Downloaded 57951526 bytes. 09:01:50 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:01:50 INFO - mkdir: /builds/slave/test-pgo/properties 09:01:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:01:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:01:50 INFO - Contents: 09:01:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:01:50 INFO - Running post-action listener: _resource_record_post_action 09:01:50 INFO - Running post-action listener: set_extra_try_arguments 09:01:50 INFO - ##### 09:01:50 INFO - ##### Running populate-webroot step. 09:01:50 INFO - ##### 09:01:50 INFO - Running pre-action listener: _resource_record_pre_action 09:01:50 INFO - Running main action method: populate_webroot 09:01:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:50 INFO - '--tppagecycles', 09:01:50 INFO - '7'], 09:01:50 INFO - 'tests': ['tsvgm']}}, 09:01:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:01:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:01:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:01:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'tests': ['damp', 'tps']}, 09:01:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['damp', 'tps']}, 09:01:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:01:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:50 INFO - 'other': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'other_l64': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'other_nol64': {'tests': ['a11yr', 09:01:50 INFO - 'ts_paint', 09:01:50 INFO - 'tpaint', 09:01:50 INFO - 'sessionrestore', 09:01:50 INFO - 'sessionrestore_no_auto_restore']}, 09:01:50 INFO - 'svgr': {'tests': ['tsvgx', 09:01:50 INFO - 'tsvgr_opacity', 09:01:50 INFO - 'tart', 09:01:50 INFO - 'tscrollx', 09:01:50 INFO - 'cart']}, 09:01:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['tsvgx', 09:01:50 INFO - 'tsvgr_opacity', 09:01:50 INFO - 'tart', 09:01:50 INFO - 'tscrollx', 09:01:50 INFO - 'cart']}, 09:01:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'tests': ['tp5o']}, 09:01:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'talos_options': ['--e10s'], 09:01:50 INFO - 'tests': ['tp5o']}, 09:01:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'talos_options': ['--xperf_path', 09:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:50 INFO - 'tests': ['tp5n']}, 09:01:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:50 INFO - 'talos_options': ['--e10s', 09:01:50 INFO - '--xperf_path', 09:01:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:50 INFO - 'tests': ['tp5n']}}, 09:01:50 INFO - 'talos.zip': {'path': '', 09:01:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:50 INFO - Running post-action listener: _resource_record_post_action 09:01:50 INFO - ##### 09:01:50 INFO - ##### Running create-virtualenv step. 09:01:50 INFO - ##### 09:01:50 INFO - Running pre-action listener: _resource_record_pre_action 09:01:50 INFO - Running main action method: create_virtualenv 09:01:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:01:50 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:01:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:01:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:50 INFO - Using real prefix '/usr' 09:01:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:01:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:52 INFO - Installing pip.................done. 09:01:53 INFO - Return code: 0 09:01:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:01:53 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:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:01:53 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:01:53 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:01:53 INFO - Using env: {'DISPLAY': ':0', 09:01:53 INFO - 'HOME': '/home/cltbld', 09:01:53 INFO - 'LANG': 'en_US.UTF-8', 09:01:53 INFO - 'LANGUAGE': 'en_US:en', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MAIL': '/var/mail/cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SHLVL': '1', 09:01:53 INFO - 'TERM': 'linux', 09:01:53 INFO - 'TMOUT': '86400', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - '_': '/tools/buildbot/bin/python'} 09:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:53 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:01:53 INFO - Cleaning up... 09:01:53 INFO - Return code: 0 09:01:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:01:53 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:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:01:53 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:01:53 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:01:53 INFO - Using env: {'DISPLAY': ':0', 09:01:53 INFO - 'HOME': '/home/cltbld', 09:01:53 INFO - 'LANG': 'en_US.UTF-8', 09:01:53 INFO - 'LANGUAGE': 'en_US:en', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MAIL': '/var/mail/cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SHLVL': '1', 09:01:53 INFO - 'TERM': 'linux', 09:01:53 INFO - 'TMOUT': '86400', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - '_': '/tools/buildbot/bin/python'} 09:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:53 INFO - Downloading/unpacking psutil>=0.7.1 09:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:55 INFO - Installing collected packages: psutil 09:01:55 INFO - Running setup.py install for psutil 09:01:55 INFO - building 'psutil._psutil_linux' extension 09:01:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:01:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:01:55 INFO - building 'psutil._psutil_posix' extension 09:01:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:01:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:55 INFO - Successfully installed psutil 09:01:55 INFO - Cleaning up... 09:01:55 INFO - Return code: 0 09:01:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:55 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:01:55 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:01:55 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:01:55 INFO - Using env: {'DISPLAY': ':0', 09:01:55 INFO - 'HOME': '/home/cltbld', 09:01:55 INFO - 'LANG': 'en_US.UTF-8', 09:01:55 INFO - 'LANGUAGE': 'en_US:en', 09:01:55 INFO - 'LOGNAME': 'cltbld', 09:01:55 INFO - 'MAIL': '/var/mail/cltbld', 09:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:55 INFO - 'MOZ_NO_REMOTE': '1', 09:01:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:55 INFO - 'NO_EM_RESTART': '1', 09:01:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:55 INFO - 'SHELL': '/bin/bash', 09:01:55 INFO - 'SHLVL': '1', 09:01:55 INFO - 'TERM': 'linux', 09:01:55 INFO - 'TMOUT': '86400', 09:01:55 INFO - 'USER': 'cltbld', 09:01:55 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:55 INFO - '_': '/tools/buildbot/bin/python'} 09:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:01:56 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:01:56 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:01:56 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:01:56 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:01:56 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:01:56 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:01:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:01:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:57 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:01:57 INFO - Installing collected packages: mozsystemmonitor 09:01:57 INFO - Running setup.py install for mozsystemmonitor 09:01:57 INFO - Successfully installed mozsystemmonitor 09:01:57 INFO - Cleaning up... 09:01:58 INFO - Return code: 0 09:01:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:58 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:01:58 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:01:58 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:01:58 INFO - Using env: {'DISPLAY': ':0', 09:01:58 INFO - 'HOME': '/home/cltbld', 09:01:58 INFO - 'LANG': 'en_US.UTF-8', 09:01:58 INFO - 'LANGUAGE': 'en_US:en', 09:01:58 INFO - 'LOGNAME': 'cltbld', 09:01:58 INFO - 'MAIL': '/var/mail/cltbld', 09:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:58 INFO - 'MOZ_NO_REMOTE': '1', 09:01:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:58 INFO - 'NO_EM_RESTART': '1', 09:01:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:01:58 INFO - 'PWD': '/builds/slave/test-pgo', 09:01:58 INFO - 'SHELL': '/bin/bash', 09:01:58 INFO - 'SHLVL': '1', 09:01:58 INFO - 'TERM': 'linux', 09:01:58 INFO - 'TMOUT': '86400', 09:01:58 INFO - 'USER': 'cltbld', 09:01:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:58 INFO - '_': '/tools/buildbot/bin/python'} 09:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:02:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:00 INFO - Downloading docopt-0.6.1.tar.gz 09:02:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:00 INFO - Running setup.py install for blobuploader 09:02:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:02:00 INFO - Running setup.py install for requests 09:02:01 INFO - Running setup.py install for docopt 09:02:01 INFO - Successfully installed blobuploader requests docopt 09:02:01 INFO - Cleaning up... 09:02:01 INFO - Return code: 0 09:02:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:01 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:02:01 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:02:01 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:02:01 INFO - Using env: {'DISPLAY': ':0', 09:02:01 INFO - 'HOME': '/home/cltbld', 09:02:01 INFO - 'LANG': 'en_US.UTF-8', 09:02:01 INFO - 'LANGUAGE': 'en_US:en', 09:02:01 INFO - 'LOGNAME': 'cltbld', 09:02:01 INFO - 'MAIL': '/var/mail/cltbld', 09:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:01 INFO - 'MOZ_NO_REMOTE': '1', 09:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:01 INFO - 'NO_EM_RESTART': '1', 09:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:01 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:01 INFO - 'SHELL': '/bin/bash', 09:02:01 INFO - 'SHLVL': '1', 09:02:01 INFO - 'TERM': 'linux', 09:02:01 INFO - 'TMOUT': '86400', 09:02:01 INFO - 'USER': 'cltbld', 09:02:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:01 INFO - '_': '/tools/buildbot/bin/python'} 09:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:02:01 INFO - Running setup.py (path:/tmp/pip-Oc7dpj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:02:01 INFO - Running setup.py (path:/tmp/pip-VE_xNJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:02:01 INFO - Running setup.py (path:/tmp/pip-7O2IM9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:02:01 INFO - Running setup.py (path:/tmp/pip-GWJuku-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:02:01 INFO - Running setup.py (path:/tmp/pip-93brQT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:02:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:02:01 INFO - Running setup.py (path:/tmp/pip-1hf43Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:02:02 INFO - Running setup.py (path:/tmp/pip-72Sl_c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:02:02 INFO - Running setup.py (path:/tmp/pip-64oX9h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:02:02 INFO - Running setup.py (path:/tmp/pip-4zCHH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:02:02 INFO - Running setup.py (path:/tmp/pip-EgNMzU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:02:02 INFO - Running setup.py (path:/tmp/pip-7M2LuZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:02:02 INFO - Running setup.py (path:/tmp/pip-q2B5hU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:02:02 INFO - Running setup.py (path:/tmp/pip-_57RwM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:02:02 INFO - Running setup.py (path:/tmp/pip-O3cBzF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:02:02 INFO - Running setup.py (path:/tmp/pip-Faem2u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:02:02 INFO - Running setup.py (path:/tmp/pip-hnE0zs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:02:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:02:02 INFO - Running setup.py (path:/tmp/pip-MH11hS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:02:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:02 INFO - Running setup.py install for manifestparser 09:02:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:02:02 INFO - Running setup.py install for mozcrash 09:02:03 INFO - Running setup.py install for mozdebug 09:02:03 INFO - Running setup.py install for mozdevice 09:02:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for mozfile 09:02:03 INFO - Running setup.py install for mozhttpd 09:02:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for mozinfo 09:02:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for mozInstall 09:02:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for mozleak 09:02:03 INFO - Running setup.py install for mozlog 09:02:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for moznetwork 09:02:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:02:03 INFO - Running setup.py install for mozprocess 09:02:04 INFO - Running setup.py install for mozprofile 09:02:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Running setup.py install for mozrunner 09:02:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Running setup.py install for mozscreenshot 09:02:04 INFO - Running setup.py install for moztest 09:02:04 INFO - Running setup.py install for mozversion 09:02:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:02:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:04 INFO - Cleaning up... 09:02:04 INFO - Return code: 0 09:02:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:04 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:02:04 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:02:04 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:02:04 INFO - Using env: {'DISPLAY': ':0', 09:02:04 INFO - 'HOME': '/home/cltbld', 09:02:04 INFO - 'LANG': 'en_US.UTF-8', 09:02:04 INFO - 'LANGUAGE': 'en_US:en', 09:02:04 INFO - 'LOGNAME': 'cltbld', 09:02:04 INFO - 'MAIL': '/var/mail/cltbld', 09:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:04 INFO - 'MOZ_NO_REMOTE': '1', 09:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:04 INFO - 'NO_EM_RESTART': '1', 09:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:04 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:04 INFO - 'SHELL': '/bin/bash', 09:02:04 INFO - 'SHLVL': '1', 09:02:04 INFO - 'TERM': 'linux', 09:02:04 INFO - 'TMOUT': '86400', 09:02:04 INFO - 'USER': 'cltbld', 09:02:04 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:04 INFO - '_': '/tools/buildbot/bin/python'} 09:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:02:04 INFO - Running setup.py (path:/tmp/pip-qFN9c_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:02:04 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:02:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:02:04 INFO - Running setup.py (path:/tmp/pip-8ngkew-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:02:04 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:02:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:02:04 INFO - Running setup.py (path:/tmp/pip-TwnRjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:02:05 INFO - Running setup.py (path:/tmp/pip-oF7A1x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:02:05 INFO - Running setup.py (path:/tmp/pip-LSKQ1p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:02:05 INFO - Running setup.py (path:/tmp/pip-YAr4x0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:02:05 INFO - Running setup.py (path:/tmp/pip-83ZDCn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:02:05 INFO - Running setup.py (path:/tmp/pip-2qYGdR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:02:05 INFO - Running setup.py (path:/tmp/pip-tF9DqC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:02:05 INFO - Running setup.py (path:/tmp/pip-J_jPIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:02:05 INFO - Running setup.py (path:/tmp/pip-N3v43U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:02:05 INFO - Running setup.py (path:/tmp/pip-kl8o1f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:02:05 INFO - Running setup.py (path:/tmp/pip-SrxQ0u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:02:05 INFO - Running setup.py (path:/tmp/pip-4MKe5d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:02:05 INFO - Running setup.py (path:/tmp/pip-dfP2Gu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:02:05 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:02:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:02:05 INFO - Running setup.py (path:/tmp/pip-o3EwZ7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:02:06 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:02:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:02:06 INFO - Running setup.py (path:/tmp/pip-VyxwmT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:07 INFO - Downloading blessings-1.6.tar.gz 09:02:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:07 INFO - Installing collected packages: blessings 09:02:07 INFO - Running setup.py install for blessings 09:02:08 INFO - Successfully installed blessings 09:02:08 INFO - Cleaning up... 09:02:08 INFO - Return code: 0 09:02:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:02:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:02:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:02:08 INFO - Reading from file tmpfile_stdout 09:02:08 INFO - Current package versions: 09:02:08 INFO - argparse == 1.2.1 09:02:08 INFO - blessings == 1.6 09:02:08 INFO - blobuploader == 1.2.4 09:02:08 INFO - docopt == 0.6.1 09:02:08 INFO - manifestparser == 1.1 09:02:08 INFO - mozInstall == 1.12 09:02:08 INFO - mozcrash == 0.16 09:02:08 INFO - mozdebug == 0.1 09:02:08 INFO - mozdevice == 0.47 09:02:08 INFO - mozfile == 1.2 09:02:08 INFO - mozhttpd == 0.7 09:02:08 INFO - mozinfo == 0.9 09:02:08 INFO - mozleak == 0.1 09:02:08 INFO - mozlog == 3.1 09:02:08 INFO - moznetwork == 0.27 09:02:08 INFO - mozprocess == 0.22 09:02:08 INFO - mozprofile == 0.27 09:02:08 INFO - mozrunner == 6.11 09:02:08 INFO - mozscreenshot == 0.1 09:02:08 INFO - mozsystemmonitor == 0.0 09:02:08 INFO - moztest == 0.7 09:02:08 INFO - mozversion == 1.4 09:02:08 INFO - psutil == 3.1.1 09:02:08 INFO - requests == 1.2.3 09:02:08 INFO - wsgiref == 0.1.2 09:02:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:02: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:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:08 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:02:08 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:02:08 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:02:08 INFO - Using env: {'DISPLAY': ':0', 09:02:08 INFO - 'HOME': '/home/cltbld', 09:02:08 INFO - 'LANG': 'en_US.UTF-8', 09:02:08 INFO - 'LANGUAGE': 'en_US:en', 09:02:08 INFO - 'LOGNAME': 'cltbld', 09:02:08 INFO - 'MAIL': '/var/mail/cltbld', 09:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:08 INFO - 'MOZ_NO_REMOTE': '1', 09:02:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:08 INFO - 'NO_EM_RESTART': '1', 09:02:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:08 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:08 INFO - 'SHELL': '/bin/bash', 09:02:08 INFO - 'SHLVL': '1', 09:02:08 INFO - 'TERM': 'linux', 09:02:08 INFO - 'TMOUT': '86400', 09:02:08 INFO - 'USER': 'cltbld', 09:02:08 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:08 INFO - '_': '/tools/buildbot/bin/python'} 09:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 INFO - Cleaning up... 09:02:08 INFO - Return code: 0 09:02:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:02: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:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:08 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:02:08 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:02:08 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:02:08 INFO - Using env: {'DISPLAY': ':0', 09:02:08 INFO - 'HOME': '/home/cltbld', 09:02:08 INFO - 'LANG': 'en_US.UTF-8', 09:02:08 INFO - 'LANGUAGE': 'en_US:en', 09:02:08 INFO - 'LOGNAME': 'cltbld', 09:02:08 INFO - 'MAIL': '/var/mail/cltbld', 09:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:08 INFO - 'MOZ_NO_REMOTE': '1', 09:02:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:08 INFO - 'NO_EM_RESTART': '1', 09:02:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:08 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:08 INFO - 'SHELL': '/bin/bash', 09:02:08 INFO - 'SHLVL': '1', 09:02:08 INFO - 'TERM': 'linux', 09:02:08 INFO - 'TMOUT': '86400', 09:02:08 INFO - 'USER': 'cltbld', 09:02:08 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:08 INFO - '_': '/tools/buildbot/bin/python'} 09:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:08 INFO - Downloading/unpacking jsonschema 09:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:08 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:02:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:10 INFO - Downloading/unpacking functools32 (from jsonschema) 09:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:10 INFO - warning: no files found matching '*.txt' 09:02:10 INFO - no previously-included directories found matching 'build' 09:02:10 INFO - no previously-included directories found matching 'dist' 09:02:10 INFO - no previously-included directories found matching '.git*' 09:02:10 INFO - Installing collected packages: jsonschema, functools32 09:02:10 INFO - Running setup.py install for functools32 09:02:10 INFO - warning: no files found matching '*.txt' 09:02:10 INFO - no previously-included directories found matching 'build' 09:02:10 INFO - no previously-included directories found matching 'dist' 09:02:10 INFO - no previously-included directories found matching '.git*' 09:02:10 INFO - Successfully installed jsonschema functools32 09:02:10 INFO - Cleaning up... 09:02:11 INFO - Return code: 0 09:02:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:11 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 0x7f0185925f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17c78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1894600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, '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': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', '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:02:11 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:02:11 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:02:11 INFO - Using env: {'DISPLAY': ':0', 09:02:11 INFO - 'HOME': '/home/cltbld', 09:02:11 INFO - 'LANG': 'en_US.UTF-8', 09:02:11 INFO - 'LANGUAGE': 'en_US:en', 09:02:11 INFO - 'LOGNAME': 'cltbld', 09:02:11 INFO - 'MAIL': '/var/mail/cltbld', 09:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:11 INFO - 'MOZ_NO_REMOTE': '1', 09:02:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:11 INFO - 'NO_EM_RESTART': '1', 09:02:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:11 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:11 INFO - 'SHELL': '/bin/bash', 09:02:11 INFO - 'SHLVL': '1', 09:02:11 INFO - 'TERM': 'linux', 09:02:11 INFO - 'TMOUT': '86400', 09:02:11 INFO - 'USER': 'cltbld', 09:02:11 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:11 INFO - '_': '/tools/buildbot/bin/python'} 09:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:11 INFO - Downloading/unpacking flake8 09:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:02:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:12 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:02:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:02:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:13 INFO - no previously-included directories found matching 'docs/_build' 09:02:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:02:13 INFO - Running setup.py install for pep8 09:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:13 INFO - no previously-included directories found matching 'docs/_build' 09:02:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:02:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:02:13 INFO - Cleaning up... 09:02:13 INFO - Return code: 0 09:02:13 INFO - Running post-action listener: _resource_record_post_action 09:02:13 INFO - Running post-action listener: _start_resource_monitoring 09:02:13 INFO - Starting resource monitoring. 09:02:13 INFO - ##### 09:02:13 INFO - ##### Running install step. 09:02:13 INFO - ##### 09:02:13 INFO - Running pre-action listener: _resource_record_pre_action 09:02:13 INFO - Running main action method: install 09:02:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:02:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:02:14 INFO - Reading from file tmpfile_stdout 09:02:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:02:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:02:14 INFO - Reading from file tmpfile_stdout 09:02:14 INFO - Output received: 09:02:14 INFO - Usage: mozinstall [options] installer 09:02:14 INFO - Options: 09:02:14 INFO - -h, --help show this help message and exit 09:02:14 INFO - -d DEST, --destination=DEST 09:02:14 INFO - Directory to install application into. [default: 09:02:14 INFO - "/builds/slave/test-pgo"] 09:02:14 INFO - --app=APP Application being installed. [default: firefox] 09:02:14 INFO - mkdir: /builds/slave/test-pgo/build/application 09:02:14 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:02:14 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:02:28 INFO - Reading from file tmpfile_stdout 09:02:28 INFO - Output received: 09:02:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:02:28 INFO - Running post-action listener: _resource_record_post_action 09:02:28 INFO - ##### 09:02:28 INFO - ##### Running run-tests step. 09:02:28 INFO - ##### 09:02:28 INFO - Running pre-action listener: _resource_record_pre_action 09:02:28 INFO - Running main action method: run_tests 09:02:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:02:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:02:28 INFO - Python 2.7.3 09:02:28 INFO - Return code: 0 09:02:28 INFO - grabbing minidump binary from tooltool 09:02: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:02:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-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 0x1923350>, '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 0x1924250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19246e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:02:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:02:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:02:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:28 INFO - Return code: 0 09:02:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:02:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:02:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:02:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:02:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:02:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:02:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:02:29 INFO - Return code: 0 09:02:29 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/1454942347/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:02:29 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/1454942347/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --webServer localhost 09:02:29 INFO - Using env: {'DISPLAY': ':0', 09:02:29 INFO - 'HOME': '/home/cltbld', 09:02:29 INFO - 'LANG': 'en_US.UTF-8', 09:02:29 INFO - 'LANGUAGE': 'en_US:en', 09:02:29 INFO - 'LOGNAME': 'cltbld', 09:02:29 INFO - 'MAIL': '/var/mail/cltbld', 09:02:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:02:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:29 INFO - 'MOZ_NO_REMOTE': '1', 09:02:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:29 INFO - 'NO_EM_RESTART': '1', 09:02:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:02:29 INFO - 'PWD': '/builds/slave/test-pgo', 09:02:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:02:29 INFO - 'SHELL': '/bin/bash', 09:02:29 INFO - 'SHLVL': '1', 09:02:29 INFO - 'TERM': 'linux', 09:02:29 INFO - 'TMOUT': '86400', 09:02:29 INFO - 'USER': 'cltbld', 09:02:29 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599', 09:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:29 INFO - '_': '/tools/buildbot/bin/python'} 09:02:29 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/1454942347/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost'] with output_timeout 3600 09:02:30 INFO - mozversion INFO | application_buildid: 20160208063907 09:02:30 INFO - mozversion INFO | application_changeset: ca3a698f224bb04f52d698aed352a28edaa19a20 09:02:30 INFO - mozversion INFO | application_display_name: Nightly 09:02:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:30 INFO - mozversion INFO | application_name: Firefox 09:02:30 INFO - mozversion INFO | application_remotingname: firefox 09:02:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:02:30 INFO - mozversion INFO | application_vendor: Mozilla 09:02:30 INFO - mozversion INFO | application_version: 45.0 09:02:30 INFO - mozversion INFO | platform_buildid: 20160208063907 09:02:30 INFO - mozversion INFO | platform_changeset: ca3a698f224bb04f52d698aed352a28edaa19a20 09:02:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:02:30 INFO - mozversion INFO | platform_version: 45.0 09:02:30 INFO - 2016-02-08 09:02:30,162 DEBUG : using testdate: 1454950950 09:02:30 INFO - 2016-02-08 09:02:30,162 DEBUG : actual date: 1454950950 09:02:30 INFO - 2016-02-08 09:02:30,170 INFO : starting webserver on 'localhost:41140' 09:02:30 INFO - 2016-02-08 09:02:30,171 INFO : Starting test suite talos-linux64-ix-060 09:02:30 INFO - 2016-02-08 09:02:30,172 INFO : Starting test dromaeo_css 09:02:30 INFO - 2016-02-08 09:02:30,172 DEBUG : operating with platform_type : linux_ 09:02:30 INFO - 2016-02-08 09:02:30,172 INFO : Initialising browser for dromaeo_css test... 09:02:30 INFO - 2016-02-08 09:02:30,185 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIiuRoF/profile http://localhost:41140/getInfo.html 09:02:33 INFO - 2016-02-08 09:02:33,871 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:02:33 INFO - 2016-02-08 09:02:33,871 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:02:33 INFO - 2016-02-08 09:02:33,871 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:02:33 INFO - 2016-02-08 09:02:33,871 DEBUG : BROWSER_OUTPUT: __metrics 09:02:34 INFO - 2016-02-08 09:02:34,008 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:02:35 INFO - 2016-02-08 09:02:35,004 INFO : Browser initialized. 09:02:35 INFO - 2016-02-08 09:02:35,005 INFO : Running cycle 1/1 for dromaeo_css test... 09:02:35 INFO - 2016-02-08 09:02:35,005 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIiuRoF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:02:46 INFO - 2016-02-08 09:02:46,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 133857280 09:02:46 INFO - 2016-02-08 09:02:46,733 DEBUG : BROWSER_OUTPUT: 09:03:37 INFO - 2016-02-08 09:03:37,590 DEBUG : BROWSER_OUTPUT: 1454951017584 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:05:45 INFO - 2016-02-08 09:05:45,870 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41140/tests/dromaeo/cssquery-ext.html) 09:05:46 INFO - 2016-02-08 09:05:46,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 245198848 09:05:46 INFO - 2016-02-08 09:05:46,916 DEBUG : BROWSER_OUTPUT: 09:08:49 INFO - 2016-02-08 09:08:49,024 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-ext.html (next: http://localhost:41140/tests/dromaeo/cssquery-jquery.html) 09:08:49 INFO - 2016-02-08 09:08:49,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 217800704 09:08:49 INFO - 2016-02-08 09:08:49,587 DEBUG : BROWSER_OUTPUT: 09:11:51 INFO - 2016-02-08 09:11:51,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41140/tests/dromaeo/cssquery-mootools.html) 09:11:51 INFO - 2016-02-08 09:11:51,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 220397568 09:11:51 INFO - 2016-02-08 09:11:51,842 DEBUG : BROWSER_OUTPUT: 09:14:53 INFO - 2016-02-08 09:14:53,504 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41140/tests/dromaeo/cssquery-prototype.html) 09:14:54 INFO - 2016-02-08 09:14:54,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 197038080 09:14:54 INFO - 2016-02-08 09:14:54,078 DEBUG : BROWSER_OUTPUT: 09:17:56 INFO - 2016-02-08 09:17:56,434 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41140/tests/dromaeo/cssquery-yui.html) 09:17:56 INFO - 2016-02-08 09:17:56,995 DEBUG : BROWSER_OUTPUT: RSS: Main: 199110656 09:17:56 INFO - 2016-02-08 09:17:56,995 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/dromaeo/cssquery-yui.html (next: http://localhost:41140/tests/dromaeo/cssquery-dojo.html) 09:20:59 INFO - 2016-02-08 09:20:59,332 DEBUG : BROWSER_OUTPUT: RSS: Main: 239538176 09:20:59 INFO - 2016-02-08 09:20:59,332 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,333 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:20:59 INFO - 2016-02-08 09:20:59,333 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:20:59 INFO - 2016-02-08 09:20:59,333 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:20:59 INFO - 2016-02-08 09:20:59,333 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:20:59 INFO - 2016-02-08 09:20:59,335 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3291.5556399886013;3356.5972083350002;3390.271091715282;3393.6945674889257;3414.975507347795;5545.944540554593;5601;5632.408597097302;5637.126245431965;5657.575681823859;5573.498385145346;5619.719014049291;5646.887062258752;5653.462921022497;5667.036603777357;4884.487128851472;5106.387233531981;5142.228665700156;5213.1919552469235;5236.947630523684;5093.134167191568;5103.387593488768;5107.948920510803;5121.283020377132;5126.846194614168;4998.975005124988;5034.924476132861;5059.620528460362;5073.670211436263;5106.463821298766;5096.79612815487;5162.767675454614;5172.663776854492;5173.663711858717;5181.53366197044;5022.648414610979;5058.190689489664;5097.464766199532;5120.92318615221;5146.639735218536;5240.187770895517;5262.394824595144;5347.786088556453;5349.251104845324;5366.758495867656;5997.250343707036;6021.277446706379;6033.396660333975;6039.667818269993;6044.848878778066;5889.411058894119;6086.056661217518;6089.695515224221;6089.878202435926;6096.237970253718;5518.530924871391;5537.778488860441;5603.943960560424;5617.691026993472;5644.4073372295725;6085.604435711664;6086.330503644595;6087.147799308101;6090.756369745161;6091.878162436727;5735.11105778605;5947.018741907624;5949.196858424144;5949.672767997802;5953.315368732651;5873.765049398062;5937.139114828326;5966.254218222723;5981.192539007178;6034.275886893601;5918.585699001114;5980.521558275327;6017.187679663277;6019.939800602026;6023.27720673522;5673;5981.342052374235;6025.15647809307;6043.033114701627;6051.4553690168095;4559.430071241095;4560.95439045612;4565.041341318294;4565.863024109282;4574.153781550423;4044.090079732037;4104.794760261975;4134.524529679065;4136.503619565611;4138.420621113047;5630.268065151586;5703.54371650267;5710.486056254957;5714.200011998323;5715.514181294553;6033.306169790442;6059.0608455689435;6076.240469941257;6101.511879049665;6104.4200800923845;5432.75552600134;5537.58468114893;5550.084236100998;5643.661380317194;5676.347220069662;5026.648134630541;5035.320231768737;5044.772985215674;5045.899082018413;5047.697138171721;6137.5703700741415;6142.385761423822;6146.262448506208;6148.9692551536955;6152.753889844356;5901.940980590311;5923.7926672566255;6010.789622363196;6044.576879618385;6089.786857459968;6196.132541444292;6222.706332630471;6246.812595622138;6259.812205633838;6272.372762723691;5764.19301297827;6042.546808989431;6044.455998960115;6048.123022161674;6048.183495228088;5983.132445795422;6028.819135425944;6099.732415522017;6103.309730288693;6122.234720659918;6019.909701354396;6023.789167379121;6024.216851809412;6037.335893051849;6042.607230530001;5775.306963164448;5863.120531920246;5999.3100793407575;6014;6021.729022193924;5721.971390143022;5789.073748200268;5791.015527360443;5792.652440853393;5844.561657875592;5913.526917846476;5953.047512397996;5997.250343707036;6001.939980600138;6006.399360063959;5622.128570071645;5652.915206271827;5753.252077229933;5862.912056319074;5875.118732190034 09:20:59 INFO - 2016-02-08 09:20:59,336 DEBUG : BROWSER_OUTPUT: |1;ext.html;586.2496005113453;624.2540164503415;627.4039662320795;632.9556931014826;641.035241960849;31878.36243275133;32683.182920427;32695.18262043453;38478.42282365767;39487.802560987155;45107.548924510746;45694;45736.77131614337;45762;45806.31290530645;9065.501397423155;9247;9247.12152345526;9247.537623118851;9249.768755781126;8271.090180080186;8473.74578762635;10736.838947415796;11035.944820275887;11124.61063862765;10666.360018398882;10893.83659245112;10896.782064358707;10899.455027248645;10906.127509799197;10368.688939331794;10506.947465262701;10580.259381843274;10581.206409519316;10584.312019718733;10542.420166890817;10800.351978881214;10817.350958942488;10818.891811081945;10821.188410869137;40684.79657601723;41301.586984130074;41473.377899331535;41542.79228603838;41638.1672366554;3680.245549662313;3694.058015206132;3708.1100535871465;3821.961780382216;4064.654504367132;32124;32491.6750832491;32922;33483.49774753381;33516;3719.274741425423;3728.5152930119184;3822.503074600312;3845.807709614508;3934.5081864766903;286085;286986;287021;288108;288209;58906;60882.39117608856;61432.692836535534;66274.66862665753;68354.3164568348;62685;62839.371606284265;62893.68553157296;63176.05235921465;63206.68396658079;16973.405930792364;17205.397811076553;17223.22495487706;17229.39697110595;17234.310627575007;12979.610611681665;13051.086423950232;13141.54004609834;13157.539486118132;13169.275689836959;20164;20550.58898822045;20621.278255261295;20648.244899183737;20764.58470830575;7951.204879512003;8050.355971522265;8084.797880052928;8105.513669179868;8145.389095817719;998.2213873178932;1013.2198207380424;1017.8015287019108;1071.844582535528;1174.1018121137338;27955.301117472227;28312.292192695346;28724.42551148965;29082.709172908;29124;1076.7684947736275;1097.5116073347444;1169.602335206017;1179.6461061681462;1217.3183017510223;13441.663958400923;13894.166350019032;14129.858701412853;14230.573082807532;16477.258523366476;261477;263232;264118;265078;265338;49333.50666493361;49356.50643493519;49675.75162124166;49697.75151124221;49744.25383619249;1377.9724405511834;1449;1491.9403223870925;1505.5408100529226;1522.6802371502108;31131.37737245197;31533.21166970752;31696.36607267888;31833.522497163023;31901;39674.60325396709;40972.79513602413;50026.74986625189;50199;50322.993540129726;207790;208883;210530;210752;211503;33292.50061249132;33550.49674254937;33763.83118084492;33799.46504012258;33862.15344616404;46307.76846115748;47967;48009;48257.51742482671;48685;39127.69972740653;39740.205195896495;40099.599004009586;40402;40569.797151014056;44061.559384405744;44685.32972005488;45942.08115837731;46128.76935615435;46242.07515849731;40253;40787.38818917778;40870.79564602158;41154.38268426036;44391;35851.46222806608;36400.81799591091;37148.62851371452;37423.25153496969;37813.243735125696;27780.861095694392;27825.304367390978;28297.717022829507;28466;28514.287142820765 09:20:59 INFO - 2016-02-08 09:20:59,339 DEBUG : BROWSER_OUTPUT: |2;jquery.html;391.8099721635007;392.907666698326;393.91530820873527;394.5403604800407;397.7871838566368;14793.334299956501;17091.743623845654;17520.561985950357;18818.717719234206;18828.717569236473;17209.225584848682;17506.737398939025;17729.55676108098;18223.36218232362;18362.1737021834;6116.969415152918;6233.750649973996;6234.220722409699;6260.718267677966;6273;4595.58639722425;4663.370444989934;4725.3384526166365;4740.028294199698;6165.907511387333;6780.355866192704;6939.722411103549;6970.616616086113;6971.79084627462;6977.825554361156;6810.659467026629;6877.277885821992;6878.105846240007;6879.5184337096425;6907.516473846833;6536.509761767838;6644.136262285921;6647.60114393138;6681.164854393201;6690.029945657852;22252.55494890093;22395.32814015582;23168.304950851503;23212.651810222862;23465.41336466585;2446.3639453741976;2453.77915987562;2473.641322008317;2617.7251388604113;2783.846888421136;14568.344424500923;14848.554543363716;14849.331780069922;15186.544403667796;15239.23803809805;1817.1096162880226;1888.792232854385;1908.3511606054008;1911.2450582020163;1920.2606996306522;73594;75326.24673753248;77813.61093194554;78728.60635696785;78741;15438.691226175413;15935.760963585555;16240.350385984664;16265.105419202058;16274.674506509804;25173.62239566408;25206.495870082497;25278.11526596597;25471.36321591938;25633.74366256351;9510.619575217052;9558.85661715061;9564.13922746956;9622.615095396106;9651.324407291422;8012.559309237931;8088.352931765562;8102.635381407851;8117.959410202911;8188.426810123234;7990.880136797953;8115.5797735397255;8117.107118216992;8149.429539932148;8164.5917704115745;1913.4164079955747;1947.8539109566839;1964.1063316191098;1966.4297353767247;2239.1715065425897;941.4398432932394;988.3921388346115;990.4107056301489;1049.375621505203;1145.7422080031927;14244.572662820132;14415.639609009859;17092.48722538326;17362.826371736122;17970.820291797176;1005.7069880672079;1032.4631191780231;1049.6588608702202;1057.5399701130034;1073.2004656530935;6384.170057892445;6412.967935160387;6563.1796025496815;6582.341765823475;6618.271990081087;662003;667641;671287;671410;675701;90327.54836225908;92771.53614232021;92991.0536779324;95297;96610;548.4186762032259;554.3569459427046;555.0702573189891;555.547229008365;556.0796881161684;11616.651500455;11637.359945203096;11920;11951;11980.520779168735;38766;38935.80532097322;38969.610303896596;39532;40047.599524004385;52481.47518524766;53337.46662533325;53544.46455535551;54053.45946540641;56118.719406402706;9377.1091746284;9782.217422606316;9788.853167202355;10510.369377837354;10774.407407592658;11991.82012269834;12026.639200823989;12029.699257518632;17593.472195834147;18781.718274225623;14345.211013393946;14661.413543458142;15181.392744290104;15639.29623166983;15685.215739213221;11958.521659133537;16035.518934431986;16371.754423683893;16518.42185523501;17019.14904254807;12687.492500300254;16696.749548757023;16794.664106718043;16897.91551042237;16955.576110597332;10279.53742081623;10631.94684026575;10812.5134368952;10844.783104338027;10922.29005114665;15957.44148954781;16008.359665613245;16205.108719020567;16429.507114786575;16498.25757840924 09:20:59 INFO - 2016-02-08 09:20:59,340 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1554.8211955625093;1719.3982106262808;1791.7133258678614;1846.898420586868;1873.131982456228;7471;7671.386289096879;7687.538747675143;7690.5770182639935;7693;7457.776266712007;7607.505512141707;7634.5037572557885;7635.656395462203;7647.732329368473;2267.0818318581;2276.7609401012905;2315.409570559513;2317.860928344296;2320.3387034695115;2294.254367330616;2318.3508617587017;2318.7449380568123;2319.3158018384534;2321.199186280739;1962;1996.8801871887733;2231.129859354853;2251.2908433843286;2291.633338665814;2242.237639202671;2251.392124126493;2276.203328834911;2276.2602154299834;2278.6923765291804;2234.441389652587;2314.884255787204;2317.895694693743;2318.431984163891;2318.710161229846;6459.935400646027;6577.80266592003;7141.67862446186;7359.411247100219;7361.411087113018;4516.796744146522;4618.630509559227;4622.306654001893;4626.421697287839;4634.72191668501;2956.9852150739325;2979.55306703994;2981.7167369099902;3000.0699783067103;3105.9534106988413;978.4129522286571;984.5372674842812;1005.5123265216364;1018.4602160854758;1024.7694268789537;10115.089641932187;10192.643257486026;10192.949035254851;10214.795704085951;10234.692959211161;4148.875533733993;4215.093754842724;4231.640310573574;4300.913981720348;4308.418363520947;8988.370814043084;8993.730188094367;9000.8649870252;9004.909950900537;9012.504312262889;4662.836800711959;4696.553827386393;4701.412323459567;4703.31801888731;4703.506131856174;4244.72409293395;4247.193033323659;4255.510616279105;4255.5318914919335;4255.680823938217;3563.964360356415;3624.873129440457;3629.0564453242346;3648.2156336387275;3649.525561676965;1192.48723049088;1215.6353094071744;1219.5304807648943;1219.969500762488;1222.8593711723265;1414.8231471066117;1414.9575512734634;1419.396756378535;1432.0619993904008;1447.9276036198148;7341.155767086747;7379.557226566423;7407.037085178892;7410.814729631695;7417.851642967111;515.0960065085825;519.8674338043775;519.9558037566774;522.8248536740159;527.067091248488;5266.8419947401635;5785.50823180026;5842.766289348463;5851.707414629337;5854.560907931922;7648.961755191189;8710.259627931697;8772.91227087721;8824.779380515538;8886.66699994993;8593.828123437495;8640.827183456297;8688.26149777276;8729.345299102466;8747.387682862138;7038.612876291751;7129.108861392429;7143.785686429415;7213;7220.819479513054;6995.755148569776;7203.099612548432;7221.133463984355;7234.348908598251;7240.96379518099;8008.399370047153;8147.617056023571;8275.172482751677;8374.413791034569;8395.874061889199;8249.711260105867;8442.451240669337;8442.577871106543;8445.282151017233;8447;6859.862802744017;6955.339242771928;6957.304269573205;6957.826054348682;6958.895616565655;7378.409727221702;7440.516366436164;7490.5131166474;7500.174980752004;7505.061867266591;6957.199922009034;6975.546589471668;7178.676959536729;7249.420046396381;7266.273372662691;7041.3310735480045;7077.858442831011;7082.18554866197;7140.642967851691;7185.2814718527725;7066.787996360117;7096.574205547684;7159.391451726661;7165.749198778018;7169.713211471482;6512.54412191161;6542.54202205841;6576.704048317933;6579.078928950046;6620.536562440583;6498;6547.901781473186;6549.443297319591;6551.213854337319;6553.770618028346 09:20:59 INFO - 2016-02-08 09:20:59,340 DEBUG : BROWSER_OUTPUT: |4;prototype.html;365.1546669460199;366.8312576214941;368.64978270642894;370.45173143747263;378.63840032768707;6011.57918945674;6087.878242435148;6096.4513193812545;6100.847478813032;6153.138560601519;5761.366249712528;5866.677332746697;5931;5993.981023226052;6007.549433792461;4667.6965997210245;4708.293755936603;4720.59874910633;4732.100900828831;4734.195186818232;4705.341252224691;4708.270218116198;4719.292106184065;4722.976385118087;4728.054389122172;4496.910061798762;4572.794224259899;4597.747123908183;4695.3426520287185;4706.811727530895;4666.65000124992;4671.27595222741;4682.040181762731;4684.391029166203;4716.905661886777;4167.333226683716;4167.770772607505;4173.874783756492;4180.310248808943;4182.728122672017;5618.550515958714;5707.800226992035;5708.971455142698;5710.657360558338;5727.312722473288;5492.972535137339;5506.779728810843;5515.283013208297;5516.558675305966;5520.696361700105;6397.328280530523;6404.807855764335;6406.647634380107;6407.423331900151;6408.198975128109;4651.279051746985;4651.62786977041;4655.930161047587;4660.347551342815;4673.696209746356;6488.156539649815;6592.93407065928;6611.735530578771;6614.338566143395;6661.966690166615;4749.715017098985;4791.233402655558;4800.375951126401;4801.9039619208115;4803.639727020487;4866.075445665312;4868.634852386014;4870.318155458239;4874.171390863491;4874.829380971649;5514.889702206014;5515.503604675599;5517.8344649659775;5520.337559492887;5523.502884740312;6333.240011198685;6453.870922581522;6468.870622587616;6471.191101112361;6472.579282346633;1708.769316142305;1726.8532174765285;1728.1532049296006;1729.2737050438845;1731.6190438103597;1177.3936422742295;1190.547591915084;1196.1985469735298;1216.1365430544413;1218.06817784395;6221.906671399933;6231.127642130105;6232.345603711636;6234.721882014176;6236.501079913596;6673.065770792093;6677.198736151693;6679.465642748569;6682.398584127452;6686.09737685416;6065.211522502134;6134.662593557308;6173.84565385869;6220.7511699531515;6263.498920086382;3596.9100772480374;3604.819759012039;3607.3326434609157;3620.873269435557;3622.5834029086463;6908.3437073477935;6913.585184888922;6914.061523852342;6914.8617027660175;6921.723131074801;6746.257911629717;6887.5178737487895;6890.414314783199;6904.620245886424;6905.827354316082;6832.521723479408;6840.965795170992;6877.1747390313485;6902.827429314207;6904.240533541307;6705.1283333166375;6709.429698475684;6710.295418980937;6713.12729345182;6715.731370745116;6520.641364724986;6620.139381880324;6626.27111017778;6628.602283862983;6629.668516574248;6925.445964322741;6929.68816403253;6930.27232140618;6932.168139823253;6937;4669.0428462165855;4708.6468514860835;4709.175894218567;4710.882227944328;4716.551927566876;6583.30875258091;6697.129373181455;6698.196216454056;6704.229013663491;6706.798796036127;6429.839254018687;6480.611163330215;6482.092507048923;6483.935160648522;6491;6396.168498095218;6503.349665033648;6505.447037001908;6507.186601674791;6508.219013718384;6392.840178995562;6396.808095757135;6414.679266036773;6415.550911436341;6416.262129854939;6534.803955881332;6537.542372034102;6538.1827271591055;6541;6549.279579246374;6262.53031022666;6393.456556192776;6395.360463953567;6400.775972840928;6402.583832050902 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: |5;yui.html;882.7704796752844;886.0778376120655;894.9463032218072;897.5063714956772;898.0338726840572;13510.256935868536;13724.245166515837;13729.862701372984;13774.655633609165;13828.792568111487;13284.667883302922;13298.202107873534;13379.732405351888;13396.263205523717;13397.799033014513;743.0526079248974;746.8917007033976;868.956552172392;872.2803686958254;881.0264657553407;891.5007595746371;891.790429249126;895.9328050396247;898.6540182029901;899.9775005624846;919.1773362840254;926.6525053105086;929.1637526226416;932.5290728182258;932.7108596335158;764.7858599592123;766.0271455251827;906.8277027364845;912.9269658427343;913.4062859141545;673.4679603113534;675.9087523184405;684.8014075917978;685.4276678973064;686.615495322621;13341.733165336638;13465.528706495226;13492.662683432894;13493.730125397535;13494.527691530848;12291.877081229162;12588.68528286791;12846.036547258993;12858.807117893239;13050.54323098687;9787.119159275628;9806.509674516246;9850.556724947395;9930.155936745385;10000.199984001263;3346;3361.8151001694896;3364.5121457388786;3386.593608766964;3394.066631676284;45657.54342456566;45742.313865292046;46385;46608;46824.5317546827;9229.630814767334;9360.719178424659;9415.952920235492;9428.434293942386;9458;19160.904195479212;19215.51961200981;19222.61554768897;19297.324593639438;19312;7625.199354067743;7687.192844751333;7767.145613982458;7827.099883513469;8088.514689118671;4312.654987600984;4314.71954322968;4318.697691161588;4349.086691794759;4407.933880991788;2939.412117576493;2949.100524340097;2959.496885529465;2960.7779416543412;2961;508.52706982506623;531.8670332416896;534.1640332879032;536.2894165231085;542.0189457082693;567.7033279502234;631.1499850909415;649.4155260265704;659.2945548263408;661.7882277671099;8454.535000574904;9122.771930701785;9183.219426348702;9203.309751768644;9217.124373184537;470.81488325461413;582.28960667985;584.3659629302255;626.232864740689;718.9065421495277;3963.1875465529497;4131.586841315844;4173.52004519478;4272.5727427257025;4297.82808687649;57560.71219643875;59189.408105919254;59324.40675593275;59640.70179649074;60242;48454.60817742724;49009.75495122573;49461.25808112881;49486.50513494891;51359;47459.76270118696;47819;48108.5189148111;49022.019559608605;49281.50718492841;8332.916670833214;8381.119982402004;8477.74566762998;8500.70247541333;8502.022267439077;35679.643203568674;35691.28617427689;36313.09217269589;36343.818280908425;36438.63561364458;43569.78215109031;47244.29133562931;47833;48087.27869082036;48103.27845082257;10703.304285221435;10847.511861966388;11046.668599942015;11092.223544351818;11099.167562432689;11853.525858965544;11959.222650527689;12410.627681169579;12628.368581570701;12720.87279127197;10250;11240.15698822608;11263.831042534204;11569.595064172714;11920.582779602672;10903.509342079784;11042.392668403316;11183.217174797686;11190.440477976232;11316.161026546652;9611.683969030673;11003.504842281956;11239.438028098399;11736.823947640964;11760.176787624785;9162.358634895494;9330.580123894577;9477.620895164117;9672.743992643214;9828.224389953539;9274.629014839331;9877.209823214269;9906.752331191778;9973.950130249303;10056.997973627125 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,802 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952059332__endTimestamp 09:20:59 INFO - 2016-02-08 09:20:59,341 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5575.50 Median:5775.31 stddev:631.48 (10.9%) stddev-sans-first:607.47 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: Values: 3291.6 3356.6 3390.3 3393.7 3415.0 5545.9 5601.0 5632.4 5637.1 5657.6 5573.5 5619.7 5646.9 5653.5 5667.0 4884.5 5106.4 5142.2 5213.2 5236.9 5093.1 5103.4 5107.9 5121.3 5126.8 4999.0 5034.9 5059.6 5073.7 5106.5 5096.8 5162.8 5172.7 5173.7 5181.5 5022.6 5058.2 5097.5 5120.9 5146.6 5240.2 5262.4 5347.8 5349.3 5366.8 5997.3 6021.3 6033.4 6039.7 6044.8 5889.4 6086.1 6089.7 6089.9 6096.2 5518.5 5537.8 5603.9 5617.7 5644.4 6085.6 6086.3 6087.1 6090.8 6091.9 5735.1 5947.0 5949.2 5949.7 5953.3 5873.8 5937.1 5966.3 5981.2 6034.3 5918.6 5980.5 6017.2 6019.9 6023.3 5673.0 5981.3 6025.2 6043.0 6051.5 4559.4 4561.0 4565.0 4565.9 4574.2 4044.1 4104.8 4134.5 4136.5 4138.4 5630.3 5703.5 5710.5 5714.2 5715.5 6033.3 6059.1 6076.2 6101.5 6104.4 5432.8 5537.6 5550.1 5643.7 5676.3 5026.6 5035.3 5044.8 5045.9 5047.7 6137.6 6142.4 6146.3 6149.0 6152.8 5901.9 5923.8 6010.8 6044.6 6089.8 6196.1 6222.7 6246.8 6259.8 6272.4 5764.2 6042.5 6044.5 6048.1 6048.2 5983.1 6028.8 6099.7 6103.3 6122.2 6019.9 6023.8 6024.2 6037.3 6042.6 5775.3 5863.1 5999.3 6014.0 6021.7 5722.0 5789.1 5791.0 5792.7 5844.6 5913.5 5953.0 5997.3 6001.9 6006.4 5622.1 5652.9 5753.3 5862.9 5875.1 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,342 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44669.67 Median:33533.25 stddev:66345.59 (197.9%) stddev-sans-first:66449.14 09:20:59 INFO - 2016-02-08 09:20:59,343 DEBUG : BROWSER_OUTPUT: Values: 586.2 624.3 627.4 633.0 641.0 31878.4 32683.2 32695.2 38478.4 39487.8 45107.5 45694.0 45736.8 45762.0 45806.3 9065.5 9247.0 9247.1 9247.5 9249.8 8271.1 8473.7 10736.8 11035.9 11124.6 10666.4 10893.8 10896.8 10899.5 10906.1 10368.7 10506.9 10580.3 10581.2 10584.3 10542.4 10800.4 10817.4 10818.9 10821.2 40684.8 41301.6 41473.4 41542.8 41638.2 3680.2 3694.1 3708.1 3822.0 4064.7 32124.0 32491.7 32922.0 33483.5 33516.0 3719.3 3728.5 3822.5 3845.8 3934.5 286085.0 286986.0 287021.0 288108.0 288209.0 58906.0 60882.4 61432.7 66274.7 68354.3 62685.0 62839.4 62893.7 63176.1 63206.7 16973.4 17205.4 17223.2 17229.4 17234.3 12979.6 13051.1 13141.5 13157.5 13169.3 20164.0 20550.6 20621.3 20648.2 20764.6 7951.2 8050.4 8084.8 8105.5 8145.4 998.2 1013.2 1017.8 1071.8 1174.1 27955.3 28312.3 28724.4 29082.7 29124.0 1076.8 1097.5 1169.6 1179.6 1217.3 13441.7 13894.2 14129.9 14230.6 16477.3 261477.0 263232.0 264118.0 265078.0 265338.0 49333.5 49356.5 49675.8 49697.8 49744.3 1378.0 1449.0 1491.9 1505.5 1522.7 31131.4 31533.2 31696.4 31833.5 31901.0 39674.6 40972.8 50026.7 50199.0 50323.0 207790.0 208883.0 210530.0 210752.0 211503.0 33292.5 33550.5 33763.8 33799.5 33862.2 46307.8 47967.0 48009.0 48257.5 48685.0 39127.7 39740.2 40099.6 40402.0 40569.8 44061.6 44685.3 45942.1 46128.8 46242.1 40253.0 40787.4 40870.8 41154.4 44391.0 35851.5 36400.8 37148.6 37423.3 37813.2 27780.9 27825.3 28297.7 28466.0 28514.3 09:20:59 INFO - 2016-02-08 09:20:59,344 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,344 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:34645.27 Median:25552.55 stddev:109455.32 (428.4%) stddev-sans-first:109732.15 09:20:59 INFO - 2016-02-08 09:20:59,344 DEBUG : BROWSER_OUTPUT: Values: 391.8 392.9 393.9 394.5 397.8 14793.3 17091.7 17520.6 18818.7 18828.7 17209.2 17506.7 17729.6 18223.4 18362.2 6117.0 6233.8 6234.2 6260.7 6273.0 4595.6 4663.4 4725.3 4740.0 6165.9 6780.4 6939.7 6970.6 6971.8 6977.8 6810.7 6877.3 6878.1 6879.5 6907.5 6536.5 6644.1 6647.6 6681.2 6690.0 22252.6 22395.3 23168.3 23212.7 23465.4 2446.4 2453.8 2473.6 2617.7 2783.8 14568.3 14848.6 14849.3 15186.5 15239.2 1817.1 1888.8 1908.4 1911.2 1920.3 73594.0 75326.2 77813.6 78728.6 78741.0 15438.7 15935.8 16240.4 16265.1 16274.7 25173.6 25206.5 25278.1 25471.4 25633.7 9510.6 9558.9 9564.1 9622.6 9651.3 8012.6 8088.4 8102.6 8118.0 8188.4 7990.9 8115.6 8117.1 8149.4 8164.6 1913.4 1947.9 1964.1 1966.4 2239.2 941.4 988.4 990.4 1049.4 1145.7 14244.6 14415.6 17092.5 17362.8 17970.8 1005.7 1032.5 1049.7 1057.5 1073.2 6384.2 6413.0 6563.2 6582.3 6618.3 662003.0 667641.0 671287.0 671410.0 675701.0 90327.5 92771.5 92991.1 95297.0 96610.0 548.4 554.4 555.1 555.5 556.1 11616.7 11637.4 11920.0 11951.0 11980.5 38766.0 38935.8 38969.6 39532.0 40047.6 52481.5 53337.5 53544.5 54053.5 56118.7 9377.1 9782.2 9788.9 10510.4 10774.4 11991.8 12026.6 12029.7 17593.5 18781.7 14345.2 14661.4 15181.4 15639.3 15685.2 11958.5 16035.5 16371.8 16518.4 17019.1 12687.5 16696.7 16794.7 16897.9 16955.6 10279.5 10631.9 10812.5 10844.8 10922.3 15957.4 16008.4 16205.1 16429.5 16498.3 09:20:59 INFO - 2016-02-08 09:20:59,344 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,344 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5368.83 Median:6527.54 stddev:2729.39 (41.8%) stddev-sans-first:2721.99 09:20:59 INFO - 2016-02-08 09:20:59,346 DEBUG : BROWSER_OUTPUT: Values: 1554.8 1719.4 1791.7 1846.9 1873.1 7471.0 7671.4 7687.5 7690.6 7693.0 7457.8 7607.5 7634.5 7635.7 7647.7 2267.1 2276.8 2315.4 2317.9 2320.3 2294.3 2318.4 2318.7 2319.3 2321.2 1962.0 1996.9 2231.1 2251.3 2291.6 2242.2 2251.4 2276.2 2276.3 2278.7 2234.4 2314.9 2317.9 2318.4 2318.7 6459.9 6577.8 7141.7 7359.4 7361.4 4516.8 4618.6 4622.3 4626.4 4634.7 2957.0 2979.6 2981.7 3000.1 3106.0 978.4 984.5 1005.5 1018.5 1024.8 10115.1 10192.6 10192.9 10214.8 10234.7 4148.9 4215.1 4231.6 4300.9 4308.4 8988.4 8993.7 9000.9 9004.9 9012.5 4662.8 4696.6 4701.4 4703.3 4703.5 4244.7 4247.2 4255.5 4255.5 4255.7 3564.0 3624.9 3629.1 3648.2 3649.5 1192.5 1215.6 1219.5 1220.0 1222.9 1414.8 1415.0 1419.4 1432.1 1447.9 7341.2 7379.6 7407.0 7410.8 7417.9 515.1 519.9 520.0 522.8 527.1 5266.8 5785.5 5842.8 5851.7 5854.6 7649.0 8710.3 8772.9 8824.8 8886.7 8593.8 8640.8 8688.3 8729.3 8747.4 7038.6 7129.1 7143.8 7213.0 7220.8 6995.8 7203.1 7221.1 7234.3 7241.0 8008.4 8147.6 8275.2 8374.4 8395.9 8249.7 8442.5 8442.6 8445.3 8447.0 6859.9 6955.3 6957.3 6957.8 6958.9 7378.4 7440.5 7490.5 7500.2 7505.1 6957.2 6975.5 7178.7 7249.4 7266.3 7041.3 7077.9 7082.2 7140.6 7185.3 7066.8 7096.6 7159.4 7165.7 7169.7 6512.5 6542.5 6576.7 6579.1 6620.5 6498.0 6547.9 6549.4 6551.2 6553.8 09:20:59 INFO - 2016-02-08 09:20:59,346 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,346 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5442.47 Median:6143.90 stddev:1598.95 (26.0%) stddev-sans-first:1557.36 09:20:59 INFO - 2016-02-08 09:20:59,346 DEBUG : BROWSER_OUTPUT: Values: 365.2 366.8 368.6 370.5 378.6 6011.6 6087.9 6096.5 6100.8 6153.1 5761.4 5866.7 5931.0 5994.0 6007.5 4667.7 4708.3 4720.6 4732.1 4734.2 4705.3 4708.3 4719.3 4723.0 4728.1 4496.9 4572.8 4597.7 4695.3 4706.8 4666.7 4671.3 4682.0 4684.4 4716.9 4167.3 4167.8 4173.9 4180.3 4182.7 5618.6 5707.8 5709.0 5710.7 5727.3 5493.0 5506.8 5515.3 5516.6 5520.7 6397.3 6404.8 6406.6 6407.4 6408.2 4651.3 4651.6 4655.9 4660.3 4673.7 6488.2 6592.9 6611.7 6614.3 6662.0 4749.7 4791.2 4800.4 4801.9 4803.6 4866.1 4868.6 4870.3 4874.2 4874.8 5514.9 5515.5 5517.8 5520.3 5523.5 6333.2 6453.9 6468.9 6471.2 6472.6 1708.8 1726.9 1728.2 1729.3 1731.6 1177.4 1190.5 1196.2 1216.1 1218.1 6221.9 6231.1 6232.3 6234.7 6236.5 6673.1 6677.2 6679.5 6682.4 6686.1 6065.2 6134.7 6173.8 6220.8 6263.5 3596.9 3604.8 3607.3 3620.9 3622.6 6908.3 6913.6 6914.1 6914.9 6921.7 6746.3 6887.5 6890.4 6904.6 6905.8 6832.5 6841.0 6877.2 6902.8 6904.2 6705.1 6709.4 6710.3 6713.1 6715.7 6520.6 6620.1 6626.3 6628.6 6629.7 6925.4 6929.7 6930.3 6932.2 6937.0 4669.0 4708.6 4709.2 4710.9 4716.6 6583.3 6697.1 6698.2 6704.2 6706.8 6429.8 6480.6 6482.1 6483.9 6491.0 6396.2 6503.3 6505.4 6507.2 6508.2 6392.8 6396.8 6414.7 6415.6 6416.3 6534.8 6537.5 6538.2 6541.0 6549.3 6262.5 6393.5 6395.4 6400.8 6402.6 09:20:59 INFO - 2016-02-08 09:20:59,346 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,348 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13919.55 Median:48732.18 stddev:16157.82 (33.2%) stddev-sans-first:16173.49 09:20:59 INFO - 2016-02-08 09:20:59,348 DEBUG : BROWSER_OUTPUT: Values: 882.8 886.1 894.9 897.5 898.0 13510.3 13724.2 13729.9 13774.7 13828.8 13284.7 13298.2 13379.7 13396.3 13397.8 743.1 746.9 869.0 872.3 881.0 891.5 891.8 895.9 898.7 900.0 919.2 926.7 929.2 932.5 932.7 764.8 766.0 906.8 912.9 913.4 673.5 675.9 684.8 685.4 686.6 13341.7 13465.5 13492.7 13493.7 13494.5 12291.9 12588.7 12846.0 12858.8 13050.5 9787.1 9806.5 9850.6 9930.2 10000.2 3346.0 3361.8 3364.5 3386.6 3394.1 45657.5 45742.3 46385.0 46608.0 46824.5 9229.6 9360.7 9416.0 9428.4 9458.0 19160.9 19215.5 19222.6 19297.3 19312.0 7625.2 7687.2 7767.1 7827.1 8088.5 4312.7 4314.7 4318.7 4349.1 4407.9 2939.4 2949.1 2959.5 2960.8 2961.0 508.5 531.9 534.2 536.3 542.0 567.7 631.1 649.4 659.3 661.8 8454.5 9122.8 9183.2 9203.3 9217.1 470.8 582.3 584.4 626.2 718.9 3963.2 4131.6 4173.5 4272.6 4297.8 57560.7 59189.4 59324.4 59640.7 60242.0 48454.6 49009.8 49461.3 49486.5 51359.0 47459.8 47819.0 48108.5 49022.0 49281.5 8332.9 8381.1 8477.7 8500.7 8502.0 35679.6 35691.3 36313.1 36343.8 36438.6 43569.8 47244.3 47833.0 48087.3 48103.3 10703.3 10847.5 11046.7 11092.2 11099.2 11853.5 11959.2 12410.6 12628.4 12720.9 10250.0 11240.2 11263.8 11569.6 11920.6 10903.5 11042.4 11183.2 11190.4 11316.2 9611.7 11003.5 11239.4 11736.8 11760.2 9162.4 9330.6 9477.6 9672.7 9828.2 9274.6 9877.2 9906.8 9974.0 10057.0 09:20:59 INFO - 2016-02-08 09:20:59,348 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:20:59 INFO - 2016-02-08 09:20:59,348 DEBUG : BROWSER_OUTPUT: 09:20:59 INFO - 2016-02-08 09:20:59,878 INFO : Browser exited with error code: 0 09:20:59 INFO - 2016-02-08 09:20:59,899 INFO : Completed test dromaeo_css (00:18:29) 09:20:59 INFO - 2016-02-08 09:20:59,899 INFO : Starting test kraken 09:20:59 INFO - 2016-02-08 09:20:59,899 DEBUG : operating with platform_type : linux_ 09:20:59 INFO - 2016-02-08 09:20:59,899 INFO : Initialising browser for kraken test... 09:20:59 INFO - 2016-02-08 09:20:59,905 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRjeUUD/profile http://localhost:41140/getInfo.html 09:21:03 INFO - 2016-02-08 09:21:03,920 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:21:03 INFO - 2016-02-08 09:21:03,920 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:21:03 INFO - 2016-02-08 09:21:03,920 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:21:03 INFO - 2016-02-08 09:21:03,920 DEBUG : BROWSER_OUTPUT: __metrics 09:21:04 INFO - 2016-02-08 09:21:04,057 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:21:04 INFO - 2016-02-08 09:21:04,726 INFO : Browser initialized. 09:21:04 INFO - 2016-02-08 09:21:04,726 INFO : Running cycle 1/1 for kraken test... 09:21:04 INFO - 2016-02-08 09:21:04,726 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRjeUUD/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:21:16 INFO - 2016-02-08 09:21:16,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 134606848 09:21:16 INFO - 2016-02-08 09:21:16,492 DEBUG : BROWSER_OUTPUT: 09:21:18 INFO - 2016-02-08 09:21:18,960 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41140/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:19 INFO - 2016-02-08 09:21:19,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 164528128 09:21:19 INFO - 2016-02-08 09:21:19,547 DEBUG : BROWSER_OUTPUT: 09:21:22 INFO - 2016-02-08 09:21:22,104 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41140/tests/kraken/driver.html?testName=audio-dft) 09:21:22 INFO - 2016-02-08 09:21:22,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 162607104 09:21:22 INFO - 2016-02-08 09:21:22,670 DEBUG : BROWSER_OUTPUT: 09:21:25 INFO - 2016-02-08 09:21:25,454 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41140/tests/kraken/driver.html?testName=audio-fft) 09:21:26 INFO - 2016-02-08 09:21:26,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 160423936 09:21:26 INFO - 2016-02-08 09:21:26,016 DEBUG : BROWSER_OUTPUT: 09:21:27 INFO - 2016-02-08 09:21:27,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41140/tests/kraken/driver.html?testName=audio-oscillator) 09:21:28 INFO - 2016-02-08 09:21:28,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 161550336 09:21:28 INFO - 2016-02-08 09:21:28,555 DEBUG : BROWSER_OUTPUT: 09:21:30 INFO - 2016-02-08 09:21:30,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41140/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:31 INFO - 2016-02-08 09:21:31,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 159649792 09:21:31 INFO - 2016-02-08 09:21:31,315 DEBUG : BROWSER_OUTPUT: 09:21:34 INFO - 2016-02-08 09:21:34,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41140/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:34 INFO - 2016-02-08 09:21:34,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 159080448 09:21:34 INFO - 2016-02-08 09:21:34,725 DEBUG : BROWSER_OUTPUT: 09:21:38 INFO - 2016-02-08 09:21:38,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41140/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:38 INFO - 2016-02-08 09:21:38,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 159690752 09:21:38 INFO - 2016-02-08 09:21:38,608 DEBUG : BROWSER_OUTPUT: 09:21:41 INFO - 2016-02-08 09:21:41,306 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41140/tests/kraken/driver.html?testName=json-parse-financial) 09:21:41 INFO - 2016-02-08 09:21:41,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 159662080 09:21:41 INFO - 2016-02-08 09:21:41,880 DEBUG : BROWSER_OUTPUT: 09:21:43 INFO - 2016-02-08 09:21:43,429 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41140/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:43 INFO - 2016-02-08 09:21:43,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 166227968 09:21:43 INFO - 2016-02-08 09:21:43,991 DEBUG : BROWSER_OUTPUT: 09:21:45 INFO - 2016-02-08 09:21:45,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:46 INFO - 2016-02-08 09:21:46,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 162934784 09:21:46 INFO - 2016-02-08 09:21:46,361 DEBUG : BROWSER_OUTPUT: 09:21:48 INFO - 2016-02-08 09:21:48,315 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:48 INFO - 2016-02-08 09:21:48,881 DEBUG : BROWSER_OUTPUT: RSS: Main: 169111552 09:21:48 INFO - 2016-02-08 09:21:48,881 DEBUG : BROWSER_OUTPUT: 09:21:51 INFO - 2016-02-08 09:21:51,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:21:51 INFO - 2016-02-08 09:21:51,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 176599040 09:21:51 INFO - 2016-02-08 09:21:51,786 DEBUG : BROWSER_OUTPUT: 09:21:54 INFO - 2016-02-08 09:21:54,265 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:21:54 INFO - 2016-02-08 09:21:54,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 174252032 09:21:54 INFO - 2016-02-08 09:21:54,838 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41140/tests/kraken/driver.html?testName=ai-astar) 09:21:56 INFO - 2016-02-08 09:21:56,762 DEBUG : BROWSER_OUTPUT: RSS: Main: 232095744 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;112;106;110;110;112;102;110;111;111 09:21:56 INFO - 2016-02-08 09:21:56,763 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;127;153;155;120;148;145;120;164;124 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;162;162;159;160;160;162;161;161 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;72;156;79;70;75;73;155;74 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;141;94;93;94;133;92;92;132 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;109;109;109;109;110;110;110 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;147;145;147;146;146;145;145;146 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;97;95;95;94;96;95;95;96 09:21:56 INFO - 2016-02-08 09:21:56,764 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;58;57;56;58;56;57;54;55 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;66;65;65;64;65;67;64;65;65;65 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;77;78;77;78;79;78;78;80 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;117;110;113;117;119;112;116;117;112 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;136;142;141;140;135;142;145;151;134 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;72;64;67;64;68;62;62;62;63 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:21:56 INFO - 2016-02-08 09:21:56,765 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,903 09:21:56 INFO - 2016-02-08 09:21:56,766 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:21:56 INFO - 2016-02-08 09:21:56,766 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952116761__endTimestamp 09:21:56 INFO - 2016-02-08 09:21:56,766 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,769 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.30 Median:110.50 stddev:3.09 (2.8%) stddev-sans-first:3.28 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: Values: 109.0 112.0 106.0 110.0 110.0 112.0 102.0 110.0 111.0 111.0 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.00 Median:150.50 stddev:16.57 (11.0%) stddev-sans-first:16.89 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: Values: 154.0 127.0 153.0 155.0 120.0 148.0 145.0 120.0 164.0 124.0 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 162.0 162.0 159.0 160.0 160.0 162.0 161.0 161.0 09:21:56 INFO - 2016-02-08 09:21:56,770 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.70 Median:72.50 stddev:34.77 (48.0%) stddev-sans-first:36.28 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 72.0 156.0 79.0 70.0 75.0 73.0 155.0 74.0 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:93.00 stddev:20.53 (22.1%) stddev-sans-first:21.24 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 141.0 94.0 93.0 94.0 133.0 92.0 92.0 132.0 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 09:21:56 INFO - 2016-02-08 09:21:56,771 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 147.0 145.0 147.0 146.0 146.0 145.0 145.0 146.0 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 97.0 95.0 95.0 94.0 96.0 95.0 95.0 96.0 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.34 (2.3%) stddev-sans-first:1.33 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 58.0 57.0 56.0 58.0 56.0 57.0 54.0 55.0 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: Values: 66.0 65.0 65.0 64.0 65.0 67.0 64.0 65.0 65.0 65.0 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:1.25 (1.6%) stddev-sans-first:1.17 09:21:56 INFO - 2016-02-08 09:21:56,772 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 77.0 78.0 77.0 78.0 79.0 78.0 78.0 80.0 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:116.50 stddev:3.27 (2.8%) stddev-sans-first:3.07 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: Values: 110.0 117.0 110.0 113.0 117.0 119.0 112.0 116.0 117.0 112.0 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.10 Median:141.50 stddev:5.34 (3.8%) stddev-sans-first:5.34 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: Values: 135.0 136.0 142.0 141.0 140.0 135.0 142.0 145.0 151.0 134.0 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:64.50 stddev:3.25 (5.0%) stddev-sans-first:3.44 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: Values: 65.0 72.0 64.0 67.0 64.0 68.0 62.0 62.0 62.0 63.0 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:21:56 INFO - 2016-02-08 09:21:56,773 DEBUG : BROWSER_OUTPUT: 09:21:57 INFO - 2016-02-08 09:21:57,422 INFO : Browser exited with error code: 0 09:21:57 INFO - 2016-02-08 09:21:57,471 INFO : Completed test kraken (00:00:57) 09:21:57 INFO - 2016-02-08 09:21:57,471 INFO : Starting test v8_7 09:21:57 INFO - 2016-02-08 09:21:57,471 DEBUG : operating with platform_type : linux_ 09:21:57 INFO - 2016-02-08 09:21:57,471 INFO : Initialising browser for v8_7 test... 09:21:57 INFO - 2016-02-08 09:21:57,477 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptpVmug/profile http://localhost:41140/getInfo.html 09:22:01 INFO - 2016-02-08 09:22:01,402 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:22:01 INFO - 2016-02-08 09:22:01,402 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:22:01 INFO - 2016-02-08 09:22:01,402 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:22:01 INFO - 2016-02-08 09:22:01,402 DEBUG : BROWSER_OUTPUT: __metrics 09:22:01 INFO - 2016-02-08 09:22:01,559 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:22:02 INFO - 2016-02-08 09:22:02,247 INFO : Browser initialized. 09:22:02 INFO - 2016-02-08 09:22:02,248 INFO : Running cycle 1/1 for v8_7 test... 09:22:02 INFO - 2016-02-08 09:22:02,248 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmptpVmug/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:22:13 INFO - 2016-02-08 09:22:13,973 DEBUG : BROWSER_OUTPUT: RSS: Main: 134631424 09:22:13 INFO - 2016-02-08 09:22:13,973 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41140/tests/v8_7/run.html (next: http://localhost:41140/tests/v8_7/run.html) 09:22:35 INFO - 2016-02-08 09:22:35,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 422830080 09:22:35 INFO - 2016-02-08 09:22:35,525 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: |0;Richards;171.76228100309172 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;166.03021749958492 09:22:35 INFO - 2016-02-08 09:22:35,527 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.70680044593087 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5263.1578947368425 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |4;RayTrace;990.0990099009902 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |5;Earley;539.3743257820928 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |6;Boyer;10536.842105263158 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |7;RegExp;23857.14285714286 09:22:35 INFO - 2016-02-08 09:22:35,528 DEBUG : BROWSER_OUTPUT: |8;Splay;528.5412262156448 09:22:35 INFO - 2016-02-08 09:22:35,529 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 09:22:35 INFO - 2016-02-08 09:22:35,529 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:22:35 INFO - 2016-02-08 09:22:35,529 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952155526__endTimestamp 09:22:35 INFO - 2016-02-08 09:22:35,529 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.76 Median:171.76 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: Values: 171.8 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:166.03 Median:166.03 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: Values: 166.0 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,530 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.71 Median:278.71 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: Values: 278.7 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5263.16 Median:5263.16 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: Values: 5263.2 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:990.10 Median:990.10 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: Values: 990.1 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.37 Median:539.37 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,531 DEBUG : BROWSER_OUTPUT: Values: 539.4 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10536.84 Median:10536.84 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: Values: 10536.8 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23857.14 Median:23857.14 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: Values: 23857.1 09:22:35 INFO - 2016-02-08 09:22:35,532 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,533 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:528.54 Median:528.54 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: Values: 528.5 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: Values: 4840.6 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:22:35 INFO - 2016-02-08 09:22:35,534 DEBUG : BROWSER_OUTPUT: 09:22:36 INFO - 2016-02-08 09:22:36,254 INFO : Browser exited with error code: 0 09:22:36 INFO - 2016-02-08 09:22:36,268 INFO : Completed test v8_7 (00:00:38) 09:22:36 INFO - 2016-02-08 09:22:36,635 INFO : Completed test suite (00:20:06) 09:22:36 INFO - 2016-02-08 09:22:36,635 DEBUG : Working with test: dromaeo_css 09:22:36 INFO - 2016-02-08 09:22:36,635 DEBUG : Generating results file: dromaeo_css 09:22:36 INFO - 2016-02-08 09:22:36,637 DEBUG : Working with test: kraken 09:22:36 INFO - 2016-02-08 09:22:36,637 DEBUG : Generating results file: kraken 09:22:36 INFO - 2016-02-08 09:22:36,637 INFO : javascript benchmark 09:22:36 INFO - 2016-02-08 09:22:36,637 DEBUG : Working with test: v8_7 09:22:36 INFO - 2016-02-08 09:22:36,637 DEBUG : Generating results file: v8_7 09:22:36 INFO - 2016-02-08 09:22:36,638 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:36 INFO - 2016-02-08 09:22:36,684 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:22:36 INFO - 2016-02-08 09:22:36,685 DEBUG : process_Request line: dromaeo_css 7276.46 graph.html#tests=[[72,53,35]] 09:22:36 INFO - 2016-02-08 09:22:36,685 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:36 INFO - 2016-02-08 09:22:36,715 DEBUG : process_Request line: kraken 1475.70 graph.html#tests=[[232,53,35]] 09:22:36 INFO - 2016-02-08 09:22:36,715 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:22:36 INFO - 2016-02-08 09:22:36,741 DEBUG : process_Request line: v8_7 23102.09 graph.html#tests=[[230,53,35]] 09:22:36 INFO - 2016-02-08 09:22:36,743 INFO : javascript benchmark 09:22:36 INFO - 2016-02-08 09:22:36,747 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3291.5556399886013, 3356.5972083350002, 3390.271091715282, 3393.6945674889257, 3414.975507347795, 5545.944540554593, 5601.0, 5632.408597097302, 5637.126245431965, 5657.575681823859, 5573.498385145346, 5619.719014049291, 5646.887062258752, 5653.462921022497, 5667.036603777357, 4884.487128851472, 5106.387233531981, 5142.228665700156, 5213.1919552469235, 5236.947630523684, 5093.134167191568, 5103.387593488768, 5107.948920510803, 5121.283020377132, 5126.846194614168, 4998.975005124988, 5034.924476132861, 5059.620528460362, 5073.670211436263, 5106.463821298766, 5096.79612815487, 5162.767675454614, 5172.663776854492, 5173.663711858717, 5181.53366197044, 5022.648414610979, 5058.190689489664, 5097.464766199532, 5120.92318615221, 5146.639735218536, 5240.187770895517, 5262.394824595144, 5347.786088556453, 5349.251104845324, 5366.758495867656, 5997.250343707036, 6021.277446706379, 6033.396660333975, 6039.667818269993, 6044.848878778066, 5889.411058894119, 6086.056661217518, 6089.695515224221, 6089.878202435926, 6096.237970253718, 5518.530924871391, 5537.778488860441, 5603.943960560424, 5617.691026993472, 5644.4073372295725, 6085.604435711664, 6086.330503644595, 6087.147799308101, 6090.756369745161, 6091.878162436727, 5735.11105778605, 5947.018741907624, 5949.196858424144, 5949.672767997802, 5953.315368732651, 5873.765049398062, 5937.139114828326, 5966.254218222723, 5981.192539007178, 6034.275886893601, 5918.585699001114, 5980.521558275327, 6017.187679663277, 6019.939800602026, 6023.27720673522, 5673.0, 5981.342052374235, 6025.15647809307, 6043.033114701627, 6051.4553690168095, 4559.430071241095, 4560.95439045612, 4565.041341318294, 4565.863024109282, 4574.153781550423, 4044.090079732037, 4104.794760261975, 4134.524529679065, 4136.503619565611, 4138.420621113047, 5630.268065151586, 5703.54371650267, 5710.486056254957, 5714.200011998323, 5715.514181294553, 6033.306169790442, 6059.0608455689435, 6076.240469941257, 6101.511879049665, 6104.4200800923845, 5432.75552600134, 5537.58468114893, 5550.084236100998, 5643.661380317194, 5676.347220069662, 5026.648134630541, 5035.320231768737, 5044.772985215674, 5045.899082018413, 5047.697138171721, 6137.5703700741415, 6142.385761423822, 6146.262448506208, 6148.9692551536955, 6152.753889844356, 5901.940980590311, 5923.7926672566255, 6010.789622363196, 6044.576879618385, 6089.786857459968, 6196.132541444292, 6222.706332630471, 6246.812595622138, 6259.812205633838, 6272.372762723691, 5764.19301297827, 6042.546808989431, 6044.455998960115, 6048.123022161674, 6048.183495228088, 5983.132445795422, 6028.819135425944, 6099.732415522017, 6103.309730288693, 6122.234720659918, 6019.909701354396, 6023.789167379121, 6024.216851809412, 6037.335893051849, 6042.607230530001, 5775.306963164448, 5863.120531920246, 5999.3100793407575, 6014.0, 6021.729022193924, 5721.971390143022, 5789.073748200268, 5791.015527360443, 5792.652440853393, 5844.561657875592, 5913.526917846476, 5953.047512397996, 5997.250343707036, 6001.939980600138, 6006.399360063959, 5622.128570071645, 5652.915206271827, 5753.252077229933, 5862.912056319074, 5875.118732190034], "unit": "ms", "name": "dojo.html", "value": 5534.030558044634}, {"lowerIsBetter": false, "replicates": [586.2496005113453, 624.2540164503415, 627.4039662320795, 632.9556931014826, 641.035241960849, 31878.36243275133, 32683.182920427, 32695.18262043453, 38478.42282365767, 39487.802560987155, 45107.548924510746, 45694.0, 45736.77131614337, 45762.0, 45806.31290530645, 9065.501397423155, 9247.0, 9247.12152345526, 9247.537623118851, 9249.768755781126, 8271.090180080186, 8473.74578762635, 10736.838947415796, 11035.944820275887, 11124.61063862765, 10666.360018398882, 10893.83659245112, 10896.782064358707, 10899.455027248645, 10906.127509799197, 10368.688939331794, 10506.947465262701, 10580.259381843274, 10581.206409519316, 10584.312019718733, 10542.420166890817, 10800.351978881214, 10817.350958942488, 10818.891811081945, 10821.188410869137, 40684.79657601723, 41301.586984130074, 41473.377899331535, 41542.79228603838, 41638.1672366554, 3680.245549662313, 3694.058015206132, 3708.1100535871465, 3821.961780382216, 4064.654504367132, 32124.0, 32491.6750832491, 32922.0, 33483.49774753381, 33516.0, 3719.274741425423, 3728.5152930119184, 3822.503074600312, 3845.807709614508, 3934.5081864766903, 286085.0, 286986.0, 287021.0, 288108.0, 288209.0, 58906.0, 60882.39117608856, 61432.692836535534, 66274.66862665753, 68354.3164568348, 62685.0, 62839.371606284265, 62893.68553157296, 63176.05235921465, 63206.68396658079, 16973.405930792364, 17205.397811076553, 17223.22495487706, 17229.39697110595, 17234.310627575007, 12979.610611681665, 13051.086423950232, 13141.54004609834, 13157.539486118132, 13169.275689836959, 20164.0, 20550.58898822045, 20621.278255261295, 20648.244899183737, 20764.58470830575, 7951.204879512003, 8050.355971522265, 8084.797880052928, 8105.513669179868, 8145.389095817719, 998.2213873178932, 1013.2198207380424, 1017.8015287019108, 1071.844582535528, 1174.1018121137338, 27955.301117472227, 28312.292192695346, 28724.42551148965, 29082.709172908, 29124.0, 1076.7684947736275, 1097.5116073347444, 1169.602335206017, 1179.6461061681462, 1217.3183017510223, 13441.663958400923, 13894.166350019032, 14129.858701412853, 14230.573082807532, 16477.258523366476, 261477.0, 263232.0, 264118.0, 265078.0, 265338.0, 49333.50666493361, 49356.50643493519, 49675.75162124166, 49697.75151124221, 49744.25383619249, 1377.9724405511834, 1449.0, 1491.9403223870925, 1505.5408100529226, 1522.6802371502108, 31131.37737245197, 31533.21166970752, 31696.36607267888, 31833.522497163023, 31901.0, 39674.60325396709, 40972.79513602413, 50026.74986625189, 50199.0, 50322.993540129726, 207790.0, 208883.0, 210530.0, 210752.0, 211503.0, 33292.50061249132, 33550.49674254937, 33763.83118084492, 33799.46504012258, 33862.15344616404, 46307.76846115748, 47967.0, 48009.0, 48257.51742482671, 48685.0, 39127.69972740653, 39740.205195896495, 40099.599004009586, 40402.0, 40569.797151014056, 44061.559384405744, 44685.32972005488, 45942.08115837731, 46128.76935615435, 46242.07515849731, 40253.0, 40787.38818917778, 40870.79564602158, 41154.38268426036, 44391.0, 35851.46222806608, 36400.81799591091, 37148.62851371452, 37423.25153496969, 37813.243735125696, 27780.861095694392, 27825.304367390978, 28297.717022829507, 28466.0, 28514.287142820765], "unit": "ms", "name": "ext.html", "value": 19629.695774085198}, {"lowerIsBetter": false, "replicates": [391.8099721635007, 392.907666698326, 393.91530820873527, 394.5403604800407, 397.7871838566368, 14793.334299956501, 17091.743623845654, 17520.561985950357, 18818.717719234206, 18828.717569236473, 17209.225584848682, 17506.737398939025, 17729.55676108098, 18223.36218232362, 18362.1737021834, 6116.969415152918, 6233.750649973996, 6234.220722409699, 6260.718267677966, 6273.0, 4595.58639722425, 4663.370444989934, 4725.3384526166365, 4740.028294199698, 6165.907511387333, 6780.355866192704, 6939.722411103549, 6970.616616086113, 6971.79084627462, 6977.825554361156, 6810.659467026629, 6877.277885821992, 6878.105846240007, 6879.5184337096425, 6907.516473846833, 6536.509761767838, 6644.136262285921, 6647.60114393138, 6681.164854393201, 6690.029945657852, 22252.55494890093, 22395.32814015582, 23168.304950851503, 23212.651810222862, 23465.41336466585, 2446.3639453741976, 2453.77915987562, 2473.641322008317, 2617.7251388604113, 2783.846888421136, 14568.344424500923, 14848.554543363716, 14849.331780069922, 15186.544403667796, 15239.23803809805, 1817.1096162880226, 1888.792232854385, 1908.3511606054008, 1911.2450582020163, 1920.2606996306522, 73594.0, 75326.24673753248, 77813.61093194554, 78728.60635696785, 78741.0, 15438.691226175413, 15935.760963585555, 16240.350385984664, 16265.105419202058, 16274.674506509804, 25173.62239566408, 25206.495870082497, 25278.11526596597, 25471.36321591938, 25633.74366256351, 9510.619575217052, 9558.85661715061, 9564.13922746956, 9622.615095396106, 9651.324407291422, 8012.559309237931, 8088.352931765562, 8102.635381407851, 8117.959410202911, 8188.426810123234, 7990.880136797953, 8115.5797735397255, 8117.107118216992, 8149.429539932148, 8164.5917704115745, 1913.4164079955747, 1947.8539109566839, 1964.1063316191098, 1966.4297353767247, 2239.1715065425897, 941.4398432932394, 988.3921388346115, 990.4107056301489, 1049.375621505203, 1145.7422080031927, 14244.572662820132, 14415.639609009859, 17092.48722538326, 17362.826371736122, 17970.820291797176, 1005.7069880672079, 1032.4631191780231, 1049.6588608702202, 1057.5399701130034, 1073.2004656530935, 6384.170057892445, 6412.967935160387, 6563.1796025496815, 6582.341765823475, 6618.271990081087, 662003.0, 667641.0, 671287.0, 671410.0, 675701.0, 90327.54836225908, 92771.53614232021, 92991.0536779324, 95297.0, 96610.0, 548.4186762032259, 554.3569459427046, 555.0702573189891, 555.547229008365, 556.0796881161684, 11616.651500455, 11637.359945203096, 11920.0, 11951.0, 11980.520779168735, 38766.0, 38935.80532097322, 38969.610303896596, 39532.0, 40047.599524004385, 52481.47518524766, 53337.46662533325, 53544.46455535551, 54053.45946540641, 56118.719406402706, 9377.1091746284, 9782.217422606316, 9788.853167202355, 10510.369377837354, 10774.407407592658, 11991.82012269834, 12026.639200823989, 12029.699257518632, 17593.472195834147, 18781.718274225623, 14345.211013393946, 14661.413543458142, 15181.392744290104, 15639.29623166983, 15685.215739213221, 11958.521659133537, 16035.518934431986, 16371.754423683893, 16518.42185523501, 17019.14904254807, 12687.492500300254, 16696.749548757023, 16794.664106718043, 16897.91551042237, 16955.576110597332, 10279.53742081623, 10631.94684026575, 10812.5134368952, 10844.783104338027, 10922.29005114665, 15957.44148954781, 16008.359665613245, 16205.108719020567, 16429.507114786575, 16498.25757840924], "unit": "ms", "name": "jquery.html", "value": 9913.062402834414}, {"lowerIsBetter": false, "replicates": [1554.8211955625093, 1719.3982106262808, 1791.7133258678614, 1846.898420586868, 1873.131982456228, 7471.0, 7671.386289096879, 7687.538747675143, 7690.5770182639935, 7693.0, 7457.776266712007, 7607.505512141707, 7634.5037572557885, 7635.656395462203, 7647.732329368473, 2267.0818318581, 2276.7609401012905, 2315.409570559513, 2317.860928344296, 2320.3387034695115, 2294.254367330616, 2318.3508617587017, 2318.7449380568123, 2319.3158018384534, 2321.199186280739, 1962.0, 1996.8801871887733, 2231.129859354853, 2251.2908433843286, 2291.633338665814, 2242.237639202671, 2251.392124126493, 2276.203328834911, 2276.2602154299834, 2278.6923765291804, 2234.441389652587, 2314.884255787204, 2317.895694693743, 2318.431984163891, 2318.710161229846, 6459.935400646027, 6577.80266592003, 7141.67862446186, 7359.411247100219, 7361.411087113018, 4516.796744146522, 4618.630509559227, 4622.306654001893, 4626.421697287839, 4634.72191668501, 2956.9852150739325, 2979.55306703994, 2981.7167369099902, 3000.0699783067103, 3105.9534106988413, 978.4129522286571, 984.5372674842812, 1005.5123265216364, 1018.4602160854758, 1024.7694268789537, 10115.089641932187, 10192.643257486026, 10192.949035254851, 10214.795704085951, 10234.692959211161, 4148.875533733993, 4215.093754842724, 4231.640310573574, 4300.913981720348, 4308.418363520947, 8988.370814043084, 8993.730188094367, 9000.8649870252, 9004.909950900537, 9012.504312262889, 4662.836800711959, 4696.553827386393, 4701.412323459567, 4703.31801888731, 4703.506131856174, 4244.72409293395, 4247.193033323659, 4255.510616279105, 4255.5318914919335, 4255.680823938217, 3563.964360356415, 3624.873129440457, 3629.0564453242346, 3648.2156336387275, 3649.525561676965, 1192.48723049088, 1215.6353094071744, 1219.5304807648943, 1219.969500762488, 1222.8593711723265, 1414.8231471066117, 1414.9575512734634, 1419.396756378535, 1432.0619993904008, 1447.9276036198148, 7341.155767086747, 7379.557226566423, 7407.037085178892, 7410.814729631695, 7417.851642967111, 515.0960065085825, 519.8674338043775, 519.9558037566774, 522.8248536740159, 527.067091248488, 5266.8419947401635, 5785.50823180026, 5842.766289348463, 5851.707414629337, 5854.560907931922, 7648.961755191189, 8710.259627931697, 8772.91227087721, 8824.779380515538, 8886.66699994993, 8593.828123437495, 8640.827183456297, 8688.26149777276, 8729.345299102466, 8747.387682862138, 7038.612876291751, 7129.108861392429, 7143.785686429415, 7213.0, 7220.819479513054, 6995.755148569776, 7203.099612548432, 7221.133463984355, 7234.348908598251, 7240.96379518099, 8008.399370047153, 8147.617056023571, 8275.172482751677, 8374.413791034569, 8395.874061889199, 8249.711260105867, 8442.451240669337, 8442.577871106543, 8445.282151017233, 8447.0, 6859.862802744017, 6955.339242771928, 6957.304269573205, 6957.826054348682, 6958.895616565655, 7378.409727221702, 7440.516366436164, 7490.5131166474, 7500.174980752004, 7505.061867266591, 6957.199922009034, 6975.546589471668, 7178.676959536729, 7249.420046396381, 7266.273372662691, 7041.3310735480045, 7077.858442831011, 7082.18554866197, 7140.642967851691, 7185.2814718527725, 7066.787996360117, 7096.574205547684, 7159.391451726661, 7165.749198778018, 7169.713211471482, 6512.54412191161, 6542.54202205841, 6576.704048317933, 6579.078928950046, 6620.536562440583, 6498.0, 6547.901781473186, 6549.443297319591, 6551.213854337319, 6553.770618028346], "unit": "ms", "name": "mootools.html", "value": 4389.751536497132}, {"lowerIsBetter": false, "replicates": [365.1546669460199, 366.8312576214941, 368.64978270642894, 370.45173143747263, 378.63840032768707, 6011.57918945674, 6087.878242435148, 6096.4513193812545, 6100.847478813032, 6153.138560601519, 5761.366249712528, 5866.677332746697, 5931.0, 5993.981023226052, 6007.549433792461, 4667.6965997210245, 4708.293755936603, 4720.59874910633, 4732.100900828831, 4734.195186818232, 4705.341252224691, 4708.270218116198, 4719.292106184065, 4722.976385118087, 4728.054389122172, 4496.910061798762, 4572.794224259899, 4597.747123908183, 4695.3426520287185, 4706.811727530895, 4666.65000124992, 4671.27595222741, 4682.040181762731, 4684.391029166203, 4716.905661886777, 4167.333226683716, 4167.770772607505, 4173.874783756492, 4180.310248808943, 4182.728122672017, 5618.550515958714, 5707.800226992035, 5708.971455142698, 5710.657360558338, 5727.312722473288, 5492.972535137339, 5506.779728810843, 5515.283013208297, 5516.558675305966, 5520.696361700105, 6397.328280530523, 6404.807855764335, 6406.647634380107, 6407.423331900151, 6408.198975128109, 4651.279051746985, 4651.62786977041, 4655.930161047587, 4660.347551342815, 4673.696209746356, 6488.156539649815, 6592.93407065928, 6611.735530578771, 6614.338566143395, 6661.966690166615, 4749.715017098985, 4791.233402655558, 4800.375951126401, 4801.9039619208115, 4803.639727020487, 4866.075445665312, 4868.634852386014, 4870.318155458239, 4874.171390863491, 4874.829380971649, 5514.889702206014, 5515.503604675599, 5517.8344649659775, 5520.337559492887, 5523.502884740312, 6333.240011198685, 6453.870922581522, 6468.870622587616, 6471.191101112361, 6472.579282346633, 1708.769316142305, 1726.8532174765285, 1728.1532049296006, 1729.2737050438845, 1731.6190438103597, 1177.3936422742295, 1190.547591915084, 1196.1985469735298, 1216.1365430544413, 1218.06817784395, 6221.906671399933, 6231.127642130105, 6232.345603711636, 6234.721882014176, 6236.501079913596, 6673.065770792093, 6677.198736151693, 6679.465642748569, 6682.398584127452, 6686.09737685416, 6065.211522502134, 6134.662593557308, 6173.84565385869, 6220.7511699531515, 6263.498920086382, 3596.9100772480374, 3604.819759012039, 3607.3326434609157, 3620.873269435557, 3622.5834029086463, 6908.3437073477935, 6913.585184888922, 6914.061523852342, 6914.8617027660175, 6921.723131074801, 6746.257911629717, 6887.5178737487895, 6890.414314783199, 6904.620245886424, 6905.827354316082, 6832.521723479408, 6840.965795170992, 6877.1747390313485, 6902.827429314207, 6904.240533541307, 6705.1283333166375, 6709.429698475684, 6710.295418980937, 6713.12729345182, 6715.731370745116, 6520.641364724986, 6620.139381880324, 6626.27111017778, 6628.602283862983, 6629.668516574248, 6925.445964322741, 6929.68816403253, 6930.27232140618, 6932.168139823253, 6937.0, 4669.0428462165855, 4708.6468514860835, 4709.175894218567, 4710.882227944328, 4716.551927566876, 6583.30875258091, 6697.129373181455, 6698.196216454056, 6704.229013663491, 6706.798796036127, 6429.839254018687, 6480.611163330215, 6482.092507048923, 6483.935160648522, 6491.0, 6396.168498095218, 6503.349665033648, 6505.447037001908, 6507.186601674791, 6508.219013718384, 6392.840178995562, 6396.808095757135, 6414.679266036773, 6415.550911436341, 6416.262129854939, 6534.803955881332, 6537.542372034102, 6538.1827271591055, 6541.0, 6549.279579246374, 6262.53031022666, 6393.456556192776, 6395.360463953567, 6400.775972840928, 6402.583832050902], "unit": "ms", "name": "prototype.html", "value": 4937.921567608874}, {"lowerIsBetter": false, "replicates": [882.7704796752844, 886.0778376120655, 894.9463032218072, 897.5063714956772, 898.0338726840572, 13510.256935868536, 13724.245166515837, 13729.862701372984, 13774.655633609165, 13828.792568111487, 13284.667883302922, 13298.202107873534, 13379.732405351888, 13396.263205523717, 13397.799033014513, 743.0526079248974, 746.8917007033976, 868.956552172392, 872.2803686958254, 881.0264657553407, 891.5007595746371, 891.790429249126, 895.9328050396247, 898.6540182029901, 899.9775005624846, 919.1773362840254, 926.6525053105086, 929.1637526226416, 932.5290728182258, 932.7108596335158, 764.7858599592123, 766.0271455251827, 906.8277027364845, 912.9269658427343, 913.4062859141545, 673.4679603113534, 675.9087523184405, 684.8014075917978, 685.4276678973064, 686.615495322621, 13341.733165336638, 13465.528706495226, 13492.662683432894, 13493.730125397535, 13494.527691530848, 12291.877081229162, 12588.68528286791, 12846.036547258993, 12858.807117893239, 13050.54323098687, 9787.119159275628, 9806.509674516246, 9850.556724947395, 9930.155936745385, 10000.199984001263, 3346.0, 3361.8151001694896, 3364.5121457388786, 3386.593608766964, 3394.066631676284, 45657.54342456566, 45742.313865292046, 46385.0, 46608.0, 46824.5317546827, 9229.630814767334, 9360.719178424659, 9415.952920235492, 9428.434293942386, 9458.0, 19160.904195479212, 19215.51961200981, 19222.61554768897, 19297.324593639438, 19312.0, 7625.199354067743, 7687.192844751333, 7767.145613982458, 7827.099883513469, 8088.514689118671, 4312.654987600984, 4314.71954322968, 4318.697691161588, 4349.086691794759, 4407.933880991788, 2939.412117576493, 2949.100524340097, 2959.496885529465, 2960.7779416543412, 2961.0, 508.52706982506623, 531.8670332416896, 534.1640332879032, 536.2894165231085, 542.0189457082693, 567.7033279502234, 631.1499850909415, 649.4155260265704, 659.2945548263408, 661.7882277671099, 8454.535000574904, 9122.771930701785, 9183.219426348702, 9203.309751768644, 9217.124373184537, 470.81488325461413, 582.28960667985, 584.3659629302255, 626.232864740689, 718.9065421495277, 3963.1875465529497, 4131.586841315844, 4173.52004519478, 4272.5727427257025, 4297.82808687649, 57560.71219643875, 59189.408105919254, 59324.40675593275, 59640.70179649074, 60242.0, 48454.60817742724, 49009.75495122573, 49461.25808112881, 49486.50513494891, 51359.0, 47459.76270118696, 47819.0, 48108.5189148111, 49022.019559608605, 49281.50718492841, 8332.916670833214, 8381.119982402004, 8477.74566762998, 8500.70247541333, 8502.022267439077, 35679.643203568674, 35691.28617427689, 36313.09217269589, 36343.818280908425, 36438.63561364458, 43569.78215109031, 47244.29133562931, 47833.0, 48087.27869082036, 48103.27845082257, 10703.304285221435, 10847.511861966388, 11046.668599942015, 11092.223544351818, 11099.167562432689, 11853.525858965544, 11959.222650527689, 12410.627681169579, 12628.368581570701, 12720.87279127197, 10250.0, 11240.15698822608, 11263.831042534204, 11569.595064172714, 11920.582779602672, 10903.509342079784, 11042.392668403316, 11183.217174797686, 11190.440477976232, 11316.161026546652, 9611.683969030673, 11003.504842281956, 11239.438028098399, 11736.823947640964, 11760.176787624785, 9162.358634895494, 9330.580123894577, 9477.620895164117, 9672.743992643214, 9828.224389953539, 9274.629014839331, 9877.209823214269, 9906.752331191778, 9973.950130249303, 10056.997973627125], "unit": "ms", "name": "yui.html", "value": 6358.202123646054}], "name": "dromaeo_css", "value": 7276.465646612273}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 106.0, 110.0, 110.0, 112.0, 102.0, 110.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 109.3}, {"lowerIsBetter": true, "replicates": [154.0, 127.0, 153.0, 155.0, 120.0, 148.0, 145.0, 120.0, 164.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.0}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 162.0, 162.0, 159.0, 160.0, 160.0, 162.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 72.0, 156.0, 79.0, 70.0, 75.0, 73.0, 155.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 89.7}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 141.0, 94.0, 93.0, 94.0, 133.0, 92.0, 92.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 147.0, 145.0, 147.0, 146.0, 146.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 97.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 58.0, 57.0, 56.0, 58.0, 56.0, 57.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 65.0, 64.0, 65.0, 67.0, 64.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 77.0, 78.0, 77.0, 78.0, 79.0, 78.0, 78.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.7}, {"lowerIsBetter": true, "replicates": [110.0, 117.0, 110.0, 113.0, 117.0, 119.0, 112.0, 116.0, 117.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.3}, {"lowerIsBetter": true, "replicates": [135.0, 136.0, 142.0, 141.0, 140.0, 135.0, 142.0, 145.0, 151.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.1}, {"lowerIsBetter": true, "replicates": [65.0, 72.0, 64.0, 67.0, 64.0, 68.0, 62.0, 62.0, 62.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.9}], "name": "kraken", "value": 1475.6999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.76228100309172], "unit": "score", "name": "Richards", "value": 205.52824400000003}, {"lowerIsBetter": false, "replicates": [166.03021749958492], "unit": "score", "name": "DeltaBlue", "value": 398.22871400000014}, {"lowerIsBetter": false, "replicates": [278.70680044593087], "unit": "score", "name": "Encrypt", "value": 955.0574279999998}, {"lowerIsBetter": false, "replicates": [5263.1578947368425], "unit": "score", "name": "Decrypt", "value": 50.57439}, {"lowerIsBetter": false, "replicates": [990.0990099009902], "unit": "score", "name": "RayTrace", "value": 747.3888899999998}, {"lowerIsBetter": false, "replicates": [539.3743257820928], "unit": "score", "name": "Earley", "value": 1235.6224020000004}, {"lowerIsBetter": false, "replicates": [10536.842105263158], "unit": "score", "name": "Boyer", "value": 63.25073426573423}, {"lowerIsBetter": false, "replicates": [23857.14285714286], "unit": "score", "name": "RegExp", "value": 38.185}, {"lowerIsBetter": false, "replicates": [528.5412262156448], "unit": "score", "name": "Splay", "value": 154.18097200000005}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 23102.089510770704}]} 09:22:36 INFO - RETURN: dromaeo_css: 7276.46 09:22:36 INFO - RETURN: kraken: 1475.70 09:22:36 INFO - RETURN: v8_7: 23102.09 09:22:36 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23102.09"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7276.46"}}} 09:22:36 INFO - Return code: 0 09:22:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:36 INFO - # TBPL SUCCESS # 09:22:36 INFO - Running post-action listener: _resource_record_post_action 09:22:36 INFO - Running post-run listener: _resource_record_post_run 09:22:37 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 0; Write bytes: 505999360; Read time: 0; Write time: 404960 09:22:37 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 245907456; Read time: 0; Write time: 263632 09:22:37 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 0; Write bytes: 260091904; Read time: 0; Write time: 141328 09:22:37 INFO - Running post-run listener: _upload_blobber_files 09:22:37 INFO - Blob upload gear active. 09:22:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:22:37 INFO - Copying logs to upload dir... 09:22:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.829350 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-08 09:22:37.300466) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:22:37.303797) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009948 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942347/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:22:37.354879) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:22:37.355275) ========= 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=337fe3f4b1febe37b85fccc40000019e-1454950901.133760-1238173599 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004592 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:22:37.405920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:22:37.406246) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:22:37.406553) ========= ========= Total master_lag: 0.28 =========