builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1455115594.87 results: success (0) buildid: 20160210042420 builduid: f166fb553dd84067bf8a2f2f1872f2fc revision: 0a3ce3b2a051 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.867012) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.867601) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.867913) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006490 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.892074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.892407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.906094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.906483) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004730 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.957655) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.958222) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.958878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:34.959409) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-10 06:46:35-- 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% 35.4M=0s 2016-02-10 06:46:35 (35.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088168 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:35.061605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:35.061924) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022369 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:35.111533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-10 06:46:35.111935) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0a3ce3b2a051 --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 0a3ce3b2a051 --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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-10 06:46:35,449 Setting DEBUG logging. 2016-02-10 06:46:35,449 attempt 1/10 2016-02-10 06:46:35,449 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0a3ce3b2a051?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-10 06:46:35,736 unpacking tar archive at: mozilla-beta-0a3ce3b2a051/testing/mozharness/ program finished with exit code 0 elapsedTime=1.003975 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-10 06:46:36.128853) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:36.129221) ========= echo 0a3ce3b2a051 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'0a3ce3b2a051'] 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 0a3ce3b2a051 program finished with exit code 0 elapsedTime=0.004844 script_repo_revision: '0a3ce3b2a051' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:36.180350) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:36.180663) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-10 06:46:36.190824) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-10 06:46:36.191192) ========= /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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:46:36 INFO - MultiFileLogger online at 20160210 06:46:36 in /builds/slave/test-pgo 06:46:36 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 06:46:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:46:36 INFO - {'append_to_log': False, 06:46:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:46:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:46:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:46:36 INFO - 'branch': 'Mozilla-Beta', 06:46:36 INFO - 'buildbot_json_path': 'buildprops.json', 06:46:36 INFO - 'config_files': ('talos/linux_config.py',), 06:46:36 INFO - 'default_actions': ('clobber', 06:46:36 INFO - 'read-buildbot-config', 06:46:36 INFO - 'download-and-extract', 06:46:36 INFO - 'populate-webroot', 06:46:36 INFO - 'create-virtualenv', 06:46:36 INFO - 'install', 06:46:36 INFO - 'run-tests'), 06:46:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:46:36 INFO - 'download_minidump_stackwalk': True, 06:46:36 INFO - 'download_symbols': 'ondemand', 06:46:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:46:36 INFO - 'tooltool.py': '/tools/tooltool.py', 06:46:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:46:36 INFO - '/tools/misc-python/virtualenv.py')}, 06:46:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:46:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:46:36 INFO - 'installer_path': 'installer.exe', 06:46:36 INFO - 'log_level': 'info', 06:46:36 INFO - 'log_name': 'talos', 06:46:36 INFO - 'log_to_console': True, 06:46:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:46:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:46:36 INFO - 'opt_config_files': (), 06:46:36 INFO - 'pip_index': False, 06:46:36 INFO - 'sps_profile': False, 06:46:36 INFO - 'sps_profile_interval': 0, 06:46:36 INFO - 'suite': 'dromaeojs', 06:46:36 INFO - 'system_bits': '32', 06:46:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:46:36 INFO - 'title': 'talos-linux64-ix-054', 06:46:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:46:36 INFO - 'use_talos_json': True, 06:46:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:46:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:46:36 INFO - 'work_dir': 'build'} 06:46:36 INFO - ##### 06:46:36 INFO - ##### Running clobber step. 06:46:36 INFO - ##### 06:46:36 INFO - Running pre-action listener: _resource_record_pre_action 06:46:36 INFO - Running main action method: clobber 06:46:36 INFO - rmtree: /builds/slave/test-pgo/build 06:46:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:46:37 INFO - Running post-action listener: _resource_record_post_action 06:46:37 INFO - ##### 06:46:37 INFO - ##### Running read-buildbot-config step. 06:46:37 INFO - ##### 06:46:37 INFO - Running pre-action listener: _resource_record_pre_action 06:46:37 INFO - Running main action method: read_buildbot_config 06:46:37 INFO - Using buildbot properties: 06:46:37 INFO - { 06:46:37 INFO - "properties": { 06:46:37 INFO - "buildnumber": 113, 06:46:37 INFO - "product": "firefox", 06:46:37 INFO - "script_repo_revision": "production", 06:46:37 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:46:37 INFO - "repository": "", 06:46:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 06:46:37 INFO - "buildid": "20160210042420", 06:46:37 INFO - "slavename": "talos-linux64-ix-054", 06:46:37 INFO - "pgo_build": "True", 06:46:37 INFO - "basedir": "/builds/slave/test-pgo", 06:46:37 INFO - "project": "", 06:46:37 INFO - "platform": "ubuntu64_hw", 06:46:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:46:37 INFO - "slavebuilddir": "test", 06:46:37 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 06:46:37 INFO - "branch": "mozilla-beta", 06:46:37 INFO - "repo_path": "releases/mozilla-beta", 06:46:37 INFO - "stage_platform": "linux64-pgo", 06:46:37 INFO - "builduid": "f166fb553dd84067bf8a2f2f1872f2fc", 06:46:37 INFO - "revision": "0a3ce3b2a051" 06:46:37 INFO - }, 06:46:37 INFO - "sourcestamp": { 06:46:37 INFO - "repository": "", 06:46:37 INFO - "hasPatch": false, 06:46:37 INFO - "project": "", 06:46:37 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 06:46:37 INFO - "changes": [ 06:46:37 INFO - { 06:46:37 INFO - "category": null, 06:46:37 INFO - "files": [ 06:46:37 INFO - { 06:46:37 INFO - "url": null, 06:46:37 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2" 06:46:37 INFO - } 06:46:37 INFO - ], 06:46:37 INFO - "repository": "", 06:46:37 INFO - "rev": "0a3ce3b2a051", 06:46:37 INFO - "who": "sendchange", 06:46:37 INFO - "when": 1455115535, 06:46:37 INFO - "number": 7588052, 06:46:37 INFO - "comments": "Bug 1239983 - Diags around TrackBuffersMgr promises - r=jya, a=sylvestre", 06:46:37 INFO - "project": "", 06:46:37 INFO - "at": "Wed 10 Feb 2016 06:45:35", 06:46:37 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 06:46:37 INFO - "revlink": "", 06:46:37 INFO - "properties": [ 06:46:37 INFO - [ 06:46:37 INFO - "buildid", 06:46:37 INFO - "20160210042420", 06:46:37 INFO - "Change" 06:46:37 INFO - ], 06:46:37 INFO - [ 06:46:37 INFO - "builduid", 06:46:37 INFO - "f166fb553dd84067bf8a2f2f1872f2fc", 06:46:37 INFO - "Change" 06:46:37 INFO - ], 06:46:37 INFO - [ 06:46:37 INFO - "pgo_build", 06:46:37 INFO - "True", 06:46:37 INFO - "Change" 06:46:37 INFO - ] 06:46:37 INFO - ], 06:46:37 INFO - "revision": "0a3ce3b2a051" 06:46:37 INFO - } 06:46:37 INFO - ], 06:46:37 INFO - "revision": "0a3ce3b2a051" 06:46:37 INFO - } 06:46:37 INFO - } 06:46:37 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2. 06:46:37 INFO - Running post-action listener: _resource_record_post_action 06:46:37 INFO - ##### 06:46:37 INFO - ##### Running download-and-extract step. 06:46:37 INFO - ##### 06:46:37 INFO - Running pre-action listener: _resource_record_pre_action 06:46:37 INFO - Running main action method: download_and_extract 06:46:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/test_packages.json 06:46:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:46:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:46:37 INFO - Downloaded 1270 bytes. 06:46:37 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:46:37 INFO - Using the following test package requirements: 06:46:37 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:46:37 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 06:46:37 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'jsshell-linux-x86_64.zip'], 06:46:37 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 06:46:37 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:46:37 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 06:46:37 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 06:46:37 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 06:46:37 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:46:37 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:46:37 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 06:46:37 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:46:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:46:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:37 INFO - Downloaded 21581716 bytes. 06:46:37 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 06:46:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:46:37 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 06:46:38 INFO - Return code: 0 06:46:38 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 06:46:38 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')]} 06:46:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:46:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:38 INFO - Downloaded 21581716 bytes. 06:46:38 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 06:46:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:46:38 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 06:46:40 INFO - Return code: 0 06:46:40 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')]} 06:46:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 06:46:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:40 INFO - Downloaded 11132038 bytes. 06:46:40 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 06:46:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 06:46:40 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 06:46:40 INFO - Return code: 0 06:46:40 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')]} 06:46:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:46:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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 06:46:42 INFO - Downloaded 57937643 bytes. 06:46:42 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:46:42 INFO - mkdir: /builds/slave/test-pgo/properties 06:46:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:46:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:46:42 INFO - Contents: 06:46:42 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:46:42 INFO - Running post-action listener: _resource_record_post_action 06:46:42 INFO - Running post-action listener: set_extra_try_arguments 06:46:42 INFO - ##### 06:46:42 INFO - ##### Running populate-webroot step. 06:46:42 INFO - ##### 06:46:42 INFO - Running pre-action listener: _resource_record_pre_action 06:46:42 INFO - Running main action method: populate_webroot 06:46:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:46:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:46:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:46:42 INFO - '--tppagecycles', 06:46:42 INFO - '7'], 06:46:42 INFO - 'tests': ['tsvgm']}}, 06:46:42 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:46:42 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:46:42 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:46:42 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:46:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:46:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:46:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'tests': ['damp', 'tps']}, 06:46:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['damp', 'tps']}, 06:46:42 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:46:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:46:42 INFO - 'other': {'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'other_l64': {'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'other_nol64': {'tests': ['a11yr', 06:46:42 INFO - 'ts_paint', 06:46:42 INFO - 'tpaint', 06:46:42 INFO - 'sessionrestore', 06:46:42 INFO - 'sessionrestore_no_auto_restore']}, 06:46:42 INFO - 'svgr': {'tests': ['tsvgx', 06:46:42 INFO - 'tsvgr_opacity', 06:46:42 INFO - 'tart', 06:46:42 INFO - 'tscrollx', 06:46:42 INFO - 'cart']}, 06:46:42 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['tsvgx', 06:46:42 INFO - 'tsvgr_opacity', 06:46:42 INFO - 'tart', 06:46:42 INFO - 'tscrollx', 06:46:42 INFO - 'cart']}, 06:46:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'tests': ['tp5o']}, 06:46:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'talos_options': ['--e10s'], 06:46:42 INFO - 'tests': ['tp5o']}, 06:46:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'talos_options': ['--xperf_path', 06:46:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:46:42 INFO - 'tests': ['tp5n']}, 06:46:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:46:42 INFO - 'talos_options': ['--e10s', 06:46:42 INFO - '--xperf_path', 06:46:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:46:42 INFO - 'tests': ['tp5n']}}, 06:46:42 INFO - 'talos.zip': {'path': '', 06:46:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:46:42 INFO - Running post-action listener: _resource_record_post_action 06:46:42 INFO - ##### 06:46:42 INFO - ##### Running create-virtualenv step. 06:46:42 INFO - ##### 06:46:42 INFO - Running pre-action listener: _resource_record_pre_action 06:46:42 INFO - Running main action method: create_virtualenv 06:46:42 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:46:42 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 06:46:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:46:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:46:43 INFO - Using real prefix '/usr' 06:46:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:46:43 INFO - Installing distribute.............................................................................................................................................................................................done. 06:46:45 INFO - Installing pip.................done. 06:46:45 INFO - Return code: 0 06:46:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06:46: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')]} 06:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46: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')]} 06:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:45 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:45 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 06:46:45 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 06:46:45 INFO - Using env: {'DISPLAY': ':0', 06:46:45 INFO - 'HOME': '/home/cltbld', 06:46:45 INFO - 'LANG': 'en_US.UTF-8', 06:46:45 INFO - 'LANGUAGE': 'en_US:en', 06:46:45 INFO - 'LOGNAME': 'cltbld', 06:46:45 INFO - 'MAIL': '/var/mail/cltbld', 06:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:45 INFO - 'MOZ_NO_REMOTE': '1', 06:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:45 INFO - 'NO_EM_RESTART': '1', 06:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:45 INFO - 'SHELL': '/bin/bash', 06:46:45 INFO - 'SHLVL': '1', 06:46:45 INFO - 'TERM': 'linux', 06:46:45 INFO - 'TMOUT': '86400', 06:46:45 INFO - 'USER': 'cltbld', 06:46:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:45 INFO - '_': '/tools/buildbot/bin/python'} 06:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:45 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 06:46:45 INFO - Cleaning up... 06:46:45 INFO - Return code: 0 06:46:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06:46: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')]} 06:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46: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')]} 06:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:45 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:45 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 06:46:45 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 06:46:45 INFO - Using env: {'DISPLAY': ':0', 06:46:45 INFO - 'HOME': '/home/cltbld', 06:46:45 INFO - 'LANG': 'en_US.UTF-8', 06:46:45 INFO - 'LANGUAGE': 'en_US:en', 06:46:45 INFO - 'LOGNAME': 'cltbld', 06:46:45 INFO - 'MAIL': '/var/mail/cltbld', 06:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:45 INFO - 'MOZ_NO_REMOTE': '1', 06:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:45 INFO - 'NO_EM_RESTART': '1', 06:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:45 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:45 INFO - 'SHELL': '/bin/bash', 06:46:45 INFO - 'SHLVL': '1', 06:46:45 INFO - 'TERM': 'linux', 06:46:45 INFO - 'TMOUT': '86400', 06:46:45 INFO - 'USER': 'cltbld', 06:46:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:45 INFO - '_': '/tools/buildbot/bin/python'} 06:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:45 INFO - Downloading/unpacking psutil>=0.7.1 06:46:45 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 06:46:45 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 06:46:45 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 06:46:45 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 06:46:45 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 06:46:45 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 06:46:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:46:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:46:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:46:47 INFO - Installing collected packages: psutil 06:46:47 INFO - Running setup.py install for psutil 06:46:47 INFO - building 'psutil._psutil_linux' extension 06:46:47 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 06:46:48 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 06:46:48 INFO - building 'psutil._psutil_posix' extension 06:46:48 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 06:46:48 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 06:46:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:46:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:46:48 INFO - Successfully installed psutil 06:46:48 INFO - Cleaning up... 06:46:48 INFO - Return code: 0 06:46:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:48 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:48 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 06:46:48 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 06:46:48 INFO - Using env: {'DISPLAY': ':0', 06:46:48 INFO - 'HOME': '/home/cltbld', 06:46:48 INFO - 'LANG': 'en_US.UTF-8', 06:46:48 INFO - 'LANGUAGE': 'en_US:en', 06:46:48 INFO - 'LOGNAME': 'cltbld', 06:46:48 INFO - 'MAIL': '/var/mail/cltbld', 06:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:48 INFO - 'MOZ_NO_REMOTE': '1', 06:46:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:48 INFO - 'NO_EM_RESTART': '1', 06:46:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:48 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:48 INFO - 'SHELL': '/bin/bash', 06:46:48 INFO - 'SHLVL': '1', 06:46:48 INFO - 'TERM': 'linux', 06:46:48 INFO - 'TMOUT': '86400', 06:46:48 INFO - 'USER': 'cltbld', 06:46:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:48 INFO - '_': '/tools/buildbot/bin/python'} 06:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:46:48 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 06:46:48 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 06:46:48 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 06:46:48 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 06:46:48 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 06:46:48 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 06:46:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:46:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:46:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:46:50 INFO - Installing collected packages: mozsystemmonitor 06:46:50 INFO - Running setup.py install for mozsystemmonitor 06:46:50 INFO - Successfully installed mozsystemmonitor 06:46:50 INFO - Cleaning up... 06:46:50 INFO - Return code: 0 06:46:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:46:50 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')]} 06:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:50 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')]} 06:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:50 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:50 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 06:46:50 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 06:46:50 INFO - Using env: {'DISPLAY': ':0', 06:46:50 INFO - 'HOME': '/home/cltbld', 06:46:50 INFO - 'LANG': 'en_US.UTF-8', 06:46:50 INFO - 'LANGUAGE': 'en_US:en', 06:46:50 INFO - 'LOGNAME': 'cltbld', 06:46:50 INFO - 'MAIL': '/var/mail/cltbld', 06:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:50 INFO - 'MOZ_NO_REMOTE': '1', 06:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:50 INFO - 'NO_EM_RESTART': '1', 06:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:50 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:50 INFO - 'SHELL': '/bin/bash', 06:46:50 INFO - 'SHLVL': '1', 06:46:50 INFO - 'TERM': 'linux', 06:46:50 INFO - 'TMOUT': '86400', 06:46:50 INFO - 'USER': 'cltbld', 06:46:50 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:50 INFO - '_': '/tools/buildbot/bin/python'} 06:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:50 INFO - Downloading/unpacking blobuploader==1.2.4 06:46:50 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 06:46:50 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 06:46:50 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 06:46:50 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 06:46:50 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 06:46:50 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 06:46:52 INFO - Downloading blobuploader-1.2.4.tar.gz 06:46:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:46:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:46:52 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 06:46:52 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 06:46:52 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 06:46:52 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 06:46:52 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 06:46:52 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 06:46:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:46:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46: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 06:46:53 INFO - Downloading docopt-0.6.1.tar.gz 06:46:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:46:53 INFO - Installing collected packages: blobuploader, requests, docopt 06:46:53 INFO - Running setup.py install for blobuploader 06:46:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:46:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:46:53 INFO - Running setup.py install for requests 06:46:53 INFO - Running setup.py install for docopt 06:46:53 INFO - Successfully installed blobuploader requests docopt 06:46:53 INFO - Cleaning up... 06:46:53 INFO - Return code: 0 06:46:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:46: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')]} 06:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46: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')]} 06:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:53 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:53 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 06:46:53 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 06:46:53 INFO - Using env: {'DISPLAY': ':0', 06:46:53 INFO - 'HOME': '/home/cltbld', 06:46:53 INFO - 'LANG': 'en_US.UTF-8', 06:46:53 INFO - 'LANGUAGE': 'en_US:en', 06:46:53 INFO - 'LOGNAME': 'cltbld', 06:46:53 INFO - 'MAIL': '/var/mail/cltbld', 06:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:53 INFO - 'MOZ_NO_REMOTE': '1', 06:46:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:53 INFO - 'NO_EM_RESTART': '1', 06:46:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:53 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:53 INFO - 'SHELL': '/bin/bash', 06:46:53 INFO - 'SHLVL': '1', 06:46:53 INFO - 'TERM': 'linux', 06:46:53 INFO - 'TMOUT': '86400', 06:46:53 INFO - 'USER': 'cltbld', 06:46:53 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:53 INFO - '_': '/tools/buildbot/bin/python'} 06:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:46:54 INFO - Running setup.py (path:/tmp/pip-DKxjhr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:46:54 INFO - Running setup.py (path:/tmp/pip-K8Sacb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:46:54 INFO - Running setup.py (path:/tmp/pip-2TKWAv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:46:54 INFO - Running setup.py (path:/tmp/pip-GNOLAN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:46:54 INFO - Running setup.py (path:/tmp/pip-ZJS2JV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:46:54 INFO - Running setup.py (path:/tmp/pip-dzNxnV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:46:54 INFO - Running setup.py (path:/tmp/pip-tYaFnc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:46:54 INFO - Running setup.py (path:/tmp/pip-ykOzG7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:46:54 INFO - Running setup.py (path:/tmp/pip-7r7gz6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:46:54 INFO - Running setup.py (path:/tmp/pip-7eHJUz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:46:54 INFO - Running setup.py (path:/tmp/pip-hh18k4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:46:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:46:54 INFO - Running setup.py (path:/tmp/pip-j_LAnL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:46:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:46:55 INFO - Running setup.py (path:/tmp/pip-hCmrlr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:46:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:46:55 INFO - Running setup.py (path:/tmp/pip-AQJY6Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:46:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:46:55 INFO - Running setup.py (path:/tmp/pip-DQ5e0s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:46:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:46:55 INFO - Running setup.py (path:/tmp/pip-2pUe65-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:46:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:46:55 INFO - Running setup.py (path:/tmp/pip-U2vC61-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:46:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:46:55 INFO - Running setup.py install for manifestparser 06:46:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:46:55 INFO - Running setup.py install for mozcrash 06:46:55 INFO - Running setup.py install for mozdebug 06:46:55 INFO - Running setup.py install for mozdevice 06:46:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:46:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:46:55 INFO - Running setup.py install for mozfile 06:46:55 INFO - Running setup.py install for mozhttpd 06:46:55 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:46:55 INFO - Running setup.py install for mozinfo 06:46:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for mozInstall 06:46:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for mozleak 06:46:56 INFO - Running setup.py install for mozlog 06:46:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for moznetwork 06:46:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for mozprocess 06:46:56 INFO - Running setup.py install for mozprofile 06:46:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for mozrunner 06:46:56 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:46:56 INFO - Running setup.py install for mozscreenshot 06:46:56 INFO - Running setup.py install for moztest 06:46:57 INFO - Running setup.py install for mozversion 06:46:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:46:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:46:57 INFO - Cleaning up... 06:46:57 INFO - Return code: 0 06:46:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:46:57 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')]} 06:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:46:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:57 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')]} 06:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:46:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:46:57 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:46:57 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 06:46:57 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 06:46:57 INFO - Using env: {'DISPLAY': ':0', 06:46:57 INFO - 'HOME': '/home/cltbld', 06:46:57 INFO - 'LANG': 'en_US.UTF-8', 06:46:57 INFO - 'LANGUAGE': 'en_US:en', 06:46:57 INFO - 'LOGNAME': 'cltbld', 06:46:57 INFO - 'MAIL': '/var/mail/cltbld', 06:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:46:57 INFO - 'MOZ_NO_REMOTE': '1', 06:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:46:57 INFO - 'NO_EM_RESTART': '1', 06:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:46:57 INFO - 'PWD': '/builds/slave/test-pgo', 06:46:57 INFO - 'SHELL': '/bin/bash', 06:46:57 INFO - 'SHLVL': '1', 06:46:57 INFO - 'TERM': 'linux', 06:46:57 INFO - 'TMOUT': '86400', 06:46:57 INFO - 'USER': 'cltbld', 06:46:57 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:46:57 INFO - '_': '/tools/buildbot/bin/python'} 06:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:46:57 INFO - Running setup.py (path:/tmp/pip-xItBzn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:46:57 INFO - Running setup.py (path:/tmp/pip-Vlqo5L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:46:57 INFO - Running setup.py (path:/tmp/pip-KHZCF6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:46:57 INFO - Running setup.py (path:/tmp/pip-I4JLaz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:46:57 INFO - Running setup.py (path:/tmp/pip-0HzcXv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:46:57 INFO - Running setup.py (path:/tmp/pip-7dEyjf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:46:57 INFO - Running setup.py (path:/tmp/pip-DS4SJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:46:57 INFO - Running setup.py (path:/tmp/pip-uZJvqg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:46:57 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)) 06:46:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:46:57 INFO - Running setup.py (path:/tmp/pip-JnNSxM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:46:58 INFO - Running setup.py (path:/tmp/pip-pAreQW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:46:58 INFO - Running setup.py (path:/tmp/pip-uARhGb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:46:58 INFO - Running setup.py (path:/tmp/pip-inxhxs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:46:58 INFO - Running setup.py (path:/tmp/pip-AYh1Ss-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:46:58 INFO - Running setup.py (path:/tmp/pip-o3rUc7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:46:58 INFO - Running setup.py (path:/tmp/pip-q6lMJU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:46:58 INFO - Running setup.py (path:/tmp/pip-0UpRzW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:46:58 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)) 06:46:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:46:58 INFO - Running setup.py (path:/tmp/pip-AGX08Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:46:58 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)) 06:46:58 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)) 06:46:58 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)) 06:46:58 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)) 06:46:58 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)) 06:46:58 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)) 06:46:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:00 INFO - Downloading blessings-1.6.tar.gz 06:47:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:47:00 INFO - Installing collected packages: blessings 06:47:00 INFO - Running setup.py install for blessings 06:47:00 INFO - Successfully installed blessings 06:47:00 INFO - Cleaning up... 06:47:00 INFO - Return code: 0 06:47:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:47:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:47:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:47:00 INFO - Reading from file tmpfile_stdout 06:47:00 INFO - Current package versions: 06:47:00 INFO - argparse == 1.2.1 06:47:00 INFO - blessings == 1.6 06:47:00 INFO - blobuploader == 1.2.4 06:47:00 INFO - docopt == 0.6.1 06:47:00 INFO - manifestparser == 1.1 06:47:00 INFO - mozInstall == 1.12 06:47:00 INFO - mozcrash == 0.16 06:47:00 INFO - mozdebug == 0.1 06:47:00 INFO - mozdevice == 0.47 06:47:00 INFO - mozfile == 1.2 06:47:00 INFO - mozhttpd == 0.7 06:47:00 INFO - mozinfo == 0.9 06:47:00 INFO - mozleak == 0.1 06:47:00 INFO - mozlog == 3.1 06:47:00 INFO - moznetwork == 0.27 06:47:00 INFO - mozprocess == 0.22 06:47:00 INFO - mozprofile == 0.27 06:47:00 INFO - mozrunner == 6.11 06:47:00 INFO - mozscreenshot == 0.1 06:47:00 INFO - mozsystemmonitor == 0.0 06:47:00 INFO - moztest == 0.7 06:47:00 INFO - mozversion == 1.4 06:47:00 INFO - psutil == 3.1.1 06:47:00 INFO - requests == 1.2.3 06:47:00 INFO - wsgiref == 0.1.2 06:47:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:00 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:47:00 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 06:47:00 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 06:47:00 INFO - Using env: {'DISPLAY': ':0', 06:47:00 INFO - 'HOME': '/home/cltbld', 06:47:00 INFO - 'LANG': 'en_US.UTF-8', 06:47:00 INFO - 'LANGUAGE': 'en_US:en', 06:47:00 INFO - 'LOGNAME': 'cltbld', 06:47:00 INFO - 'MAIL': '/var/mail/cltbld', 06:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:00 INFO - 'MOZ_NO_REMOTE': '1', 06:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:00 INFO - 'NO_EM_RESTART': '1', 06:47:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:00 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:00 INFO - 'SHELL': '/bin/bash', 06:47:00 INFO - 'SHLVL': '1', 06:47:00 INFO - 'TERM': 'linux', 06:47:00 INFO - 'TMOUT': '86400', 06:47:00 INFO - 'USER': 'cltbld', 06:47:00 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:00 INFO - '_': '/tools/buildbot/bin/python'} 06:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 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)) 06:47:01 INFO - Cleaning up... 06:47:01 INFO - Return code: 0 06:47:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 06:47: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')]} 06:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47: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')]} 06:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:01 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:47:01 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 06:47:01 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 06:47:01 INFO - Using env: {'DISPLAY': ':0', 06:47:01 INFO - 'HOME': '/home/cltbld', 06:47:01 INFO - 'LANG': 'en_US.UTF-8', 06:47:01 INFO - 'LANGUAGE': 'en_US:en', 06:47:01 INFO - 'LOGNAME': 'cltbld', 06:47:01 INFO - 'MAIL': '/var/mail/cltbld', 06:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:01 INFO - 'MOZ_NO_REMOTE': '1', 06:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:01 INFO - 'NO_EM_RESTART': '1', 06:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:01 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:01 INFO - 'SHELL': '/bin/bash', 06:47:01 INFO - 'SHLVL': '1', 06:47:01 INFO - 'TERM': 'linux', 06:47:01 INFO - 'TMOUT': '86400', 06:47:01 INFO - 'USER': 'cltbld', 06:47:01 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:01 INFO - '_': '/tools/buildbot/bin/python'} 06:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:01 INFO - Downloading/unpacking jsonschema 06:47:01 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 06:47:01 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 06:47:01 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 06:47:01 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 06:47:01 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 06:47:01 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 06:47:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:47:03 INFO - Downloading/unpacking functools32 (from jsonschema) 06:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:03 INFO - Downloading functools32-3.2.3-2.tar.gz 06:47:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:47:03 INFO - warning: no files found matching '*.txt' 06:47:03 INFO - no previously-included directories found matching 'build' 06:47:03 INFO - no previously-included directories found matching 'dist' 06:47:03 INFO - no previously-included directories found matching '.git*' 06:47:03 INFO - Installing collected packages: jsonschema, functools32 06:47:03 INFO - Running setup.py install for functools32 06:47:03 INFO - warning: no files found matching '*.txt' 06:47:03 INFO - no previously-included directories found matching 'build' 06:47:03 INFO - no previously-included directories found matching 'dist' 06:47:03 INFO - no previously-included directories found matching '.git*' 06:47:03 INFO - Successfully installed jsonschema functools32 06:47:03 INFO - Cleaning up... 06:47:03 INFO - Return code: 0 06:47:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 06:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:47:03 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 0x7f0d01314f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2292600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, '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': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', '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 06:47:03 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 06:47:03 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 06:47:03 INFO - Using env: {'DISPLAY': ':0', 06:47:03 INFO - 'HOME': '/home/cltbld', 06:47:03 INFO - 'LANG': 'en_US.UTF-8', 06:47:03 INFO - 'LANGUAGE': 'en_US:en', 06:47:03 INFO - 'LOGNAME': 'cltbld', 06:47:03 INFO - 'MAIL': '/var/mail/cltbld', 06:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:03 INFO - 'MOZ_NO_REMOTE': '1', 06:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:03 INFO - 'NO_EM_RESTART': '1', 06:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:03 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:03 INFO - 'SHELL': '/bin/bash', 06:47:03 INFO - 'SHLVL': '1', 06:47:03 INFO - 'TERM': 'linux', 06:47:03 INFO - 'TMOUT': '86400', 06:47:03 INFO - 'USER': 'cltbld', 06:47:03 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:03 INFO - '_': '/tools/buildbot/bin/python'} 06:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:03 INFO - Downloading/unpacking flake8 06:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:47:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:47:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:47:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47: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 06:47:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 06:47:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:47:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:47:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:47:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:47:06 INFO - no previously-included directories found matching 'docs/_build' 06:47:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:47:06 INFO - Running setup.py install for pep8 06:47:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:47:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:47:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:47:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:47:06 INFO - no previously-included directories found matching 'docs/_build' 06:47:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 06:47:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:47:06 INFO - Cleaning up... 06:47:06 INFO - Return code: 0 06:47:06 INFO - Running post-action listener: _resource_record_post_action 06:47:06 INFO - Running post-action listener: _start_resource_monitoring 06:47:06 INFO - Starting resource monitoring. 06:47:06 INFO - ##### 06:47:06 INFO - ##### Running install step. 06:47:06 INFO - ##### 06:47:06 INFO - Running pre-action listener: _resource_record_pre_action 06:47:06 INFO - Running main action method: install 06:47:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:47:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:47:06 INFO - Reading from file tmpfile_stdout 06:47:06 INFO - Detecting whether we're running mozinstall >=1.0... 06:47:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:47:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:47:06 INFO - Reading from file tmpfile_stdout 06:47:06 INFO - Output received: 06:47:06 INFO - Usage: mozinstall [options] installer 06:47:06 INFO - Options: 06:47:06 INFO - -h, --help show this help message and exit 06:47:06 INFO - -d DEST, --destination=DEST 06:47:06 INFO - Directory to install application into. [default: 06:47:06 INFO - "/builds/slave/test-pgo"] 06:47:06 INFO - --app=APP Application being installed. [default: firefox] 06:47:06 INFO - mkdir: /builds/slave/test-pgo/build/application 06:47:06 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'] 06:47:06 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 06:47:21 INFO - Reading from file tmpfile_stdout 06:47:21 INFO - Output received: 06:47:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:47:21 INFO - Running post-action listener: _resource_record_post_action 06:47:21 INFO - ##### 06:47:21 INFO - ##### Running run-tests step. 06:47:21 INFO - ##### 06:47:21 INFO - Running pre-action listener: _resource_record_pre_action 06:47:21 INFO - Running main action method: run_tests 06:47:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:47:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:47:21 INFO - Python 2.7.3 06:47:21 INFO - Return code: 0 06:47:21 INFO - grabbing minidump binary from tooltool 06:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:21 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 0x2321350>, '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 0x2322250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23226e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:47:21 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 06:47:21 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 06:47:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:47:21 INFO - Return code: 0 06:47:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:47:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:47:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:47:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:47:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:47:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 06:47:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 06:47:22 INFO - Return code: 0 06:47:22 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/1455107060/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:47:22 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/1455107060/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost 06:47:22 INFO - Using env: {'DISPLAY': ':0', 06:47:22 INFO - 'HOME': '/home/cltbld', 06:47:22 INFO - 'LANG': 'en_US.UTF-8', 06:47:22 INFO - 'LANGUAGE': 'en_US:en', 06:47:22 INFO - 'LOGNAME': 'cltbld', 06:47:22 INFO - 'MAIL': '/var/mail/cltbld', 06:47:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:47:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:22 INFO - 'MOZ_NO_REMOTE': '1', 06:47:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:47:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:47:22 INFO - 'NO_EM_RESTART': '1', 06:47:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:47:22 INFO - 'PWD': '/builds/slave/test-pgo', 06:47:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:47:22 INFO - 'SHELL': '/bin/bash', 06:47:22 INFO - 'SHLVL': '1', 06:47:22 INFO - 'TERM': 'linux', 06:47:22 INFO - 'TMOUT': '86400', 06:47:22 INFO - 'USER': 'cltbld', 06:47:22 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267', 06:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:22 INFO - '_': '/tools/buildbot/bin/python'} 06:47:22 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/1455107060/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost'] with output_timeout 3600 06:47:22 INFO - mozversion INFO | application_buildid: 20160210042420 06:47:22 INFO - mozversion INFO | application_changeset: 0a3ce3b2a051e7bb483f0010167aef29992811f6 06:47:22 INFO - mozversion INFO | application_display_name: Nightly 06:47:22 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:47:22 INFO - mozversion INFO | application_name: Firefox 06:47:22 INFO - mozversion INFO | application_remotingname: firefox 06:47:22 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:47:22 INFO - mozversion INFO | application_vendor: Mozilla 06:47:22 INFO - mozversion INFO | application_version: 45.0 06:47:22 INFO - mozversion INFO | platform_buildid: 20160210042420 06:47:22 INFO - mozversion INFO | platform_changeset: 0a3ce3b2a051e7bb483f0010167aef29992811f6 06:47:22 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:47:22 INFO - mozversion INFO | platform_version: 45.0 06:47:22 INFO - 2016-02-10 06:47:22,802 DEBUG : using testdate: 1455115642 06:47:22 INFO - 2016-02-10 06:47:22,802 DEBUG : actual date: 1455115642 06:47:22 INFO - 2016-02-10 06:47:22,811 INFO : starting webserver on 'localhost:60643' 06:47:22 INFO - 2016-02-10 06:47:22,812 INFO : Starting test suite talos-linux64-ix-054 06:47:22 INFO - 2016-02-10 06:47:22,812 INFO : Starting test dromaeo_css 06:47:22 INFO - 2016-02-10 06:47:22,812 DEBUG : operating with platform_type : linux_ 06:47:22 INFO - 2016-02-10 06:47:22,813 INFO : Initialising browser for dromaeo_css test... 06:47:22 INFO - 2016-02-10 06:47:22,828 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFntjdt/profile http://localhost:60643/getInfo.html 06:47:26 INFO - 2016-02-10 06:47:26,727 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:47:26 INFO - 2016-02-10 06:47:26,727 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:47:26 INFO - 2016-02-10 06:47:26,727 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:47:26 INFO - 2016-02-10 06:47:26,727 DEBUG : BROWSER_OUTPUT: __metrics 06:47:27 INFO - 2016-02-10 06:47:27,046 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] 06:47:27 INFO - 2016-02-10 06:47:27,912 INFO : Browser initialized. 06:47:27 INFO - 2016-02-10 06:47:27,912 INFO : Running cycle 1/1 for dromaeo_css test... 06:47:27 INFO - 2016-02-10 06:47:27,913 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFntjdt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:47:39 INFO - 2016-02-10 06:47:39,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 133906432 06:47:39 INFO - 2016-02-10 06:47:39,667 DEBUG : BROWSER_OUTPUT: 06:48:30 INFO - 2016-02-10 06:48:30,423 DEBUG : BROWSER_OUTPUT: 1455115710422 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] 06:50:38 INFO - 2016-02-10 06:50:38,854 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60643/tests/dromaeo/cssquery-ext.html) 06:50:39 INFO - 2016-02-10 06:50:39,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 215650304 06:50:39 INFO - 2016-02-10 06:50:39,860 DEBUG : BROWSER_OUTPUT: 06:53:41 INFO - 2016-02-10 06:53:41,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-ext.html (next: http://localhost:60643/tests/dromaeo/cssquery-jquery.html) 06:53:42 INFO - 2016-02-10 06:53:42,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 209936384 06:53:42 INFO - 2016-02-10 06:53:42,551 DEBUG : BROWSER_OUTPUT: 06:56:44 INFO - 2016-02-10 06:56:44,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60643/tests/dromaeo/cssquery-mootools.html) 06:56:44 INFO - 2016-02-10 06:56:44,869 DEBUG : BROWSER_OUTPUT: RSS: Main: 211103744 06:56:44 INFO - 2016-02-10 06:56:44,869 DEBUG : BROWSER_OUTPUT: 06:59:46 INFO - 2016-02-10 06:59:46,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60643/tests/dromaeo/cssquery-prototype.html) 06:59:47 INFO - 2016-02-10 06:59:47,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 208097280 06:59:47 INFO - 2016-02-10 06:59:47,167 DEBUG : BROWSER_OUTPUT: 07:02:49 INFO - 2016-02-10 07:02:49,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60643/tests/dromaeo/cssquery-yui.html) 07:02:50 INFO - 2016-02-10 07:02:50,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 203325440 07:02:50 INFO - 2016-02-10 07:02:50,107 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/dromaeo/cssquery-yui.html (next: http://localhost:60643/tests/dromaeo/cssquery-dojo.html) 07:05:52 INFO - 2016-02-10 07:05:52,377 DEBUG : BROWSER_OUTPUT: RSS: Main: 239206400 07:05:52 INFO - 2016-02-10 07:05:52,377 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,377 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:05:52 INFO - 2016-02-10 07:05:52,377 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:05:52 INFO - 2016-02-10 07:05:52,378 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:05:52 INFO - 2016-02-10 07:05:52,378 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:05:52 INFO - 2016-02-10 07:05:52,378 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3289.6052473703157;3367.89896303111;3384.644612315705;3388.932221355573;3406.148462884279;5552.583556233283;5573.804916827912;5575.080111781556;5576.052071147905;5624.21261023457;5587.245721827552;5623.156526521024;5628.465295796903;5628.71856407179;5632.295963004624;4932.506749325057;5028.647994640377;5082.364704411958;5104.719240441774;5158.380994280692;5081.618878584102;5132.538071573557;5137.229415587673;5137.974310128444;5139.126348520742;5016.297718319438;5089.669171503859;5121.513456221672;5155.613329000321;5161.0968080585935;5130.127878260705;5157.5616072633875;5162.6128040397125;5170.121079416508;5195.194744814522;4884.365032545782;4939.604831613498;5087.669301495428;5089.770960306795;5154.046501397216;5245.868853278698;5338.813141540067;5351.759170837322;5356.758945847446;5359.22291267764;6081.3006504252135;6086.452219300284;6130.908036379458;6135.478484328837;6137.324894261627;5677;5766.163906233614;6031.577789554689;6142.324344322121;6152;5673.716314184274;5676.091825307931;5681.460261275213;5683.914741278884;5687.374388817227;6139.447449729457;6141.96929015361;6146.200993870768;6146.754129834846;6149.13912052313;5715.399883012307;5832.212651292021;5879.764809407576;5939.70301484924;5963.910541341883;6005.549583781234;6076.088586712029;6076.149339092531;6077.270727512727;6082.239720034996;5992.101184822224;6031.42701443371;6044.546659000593;6045.909311360333;6050;5673.404292549223;5790.681512516768;5854.765809367577;5943.494802941712;6046.062860256668;4553.612942899825;4598.2412901871485;4636.142313671914;4637.837675681402;4640.071985602893;4157.625813676784;4168.333066709312;4173.311403618369;4179.101493178981;4211.284081706117;5500.064988951888;5523.061079616475;5556.16657501378;5652.773889044474;5799.9130013049835;6107.877842443127;6147.231596050494;6155;6156.476699480505;6161.661108639068;5815.73829177688;5831.94168058314;5832.854178645568;5833.183354330397;5834.066549352084;5027.673201241908;5060.974695126501;5124.308218390544;5138.665986710852;5290.365156181206;6308.905366419507;6318.4313411793155;6343;6351.96824015877;6359.841003974845;6173.166622505904;6174.073888916699;6174.413430724074;6177.289611694712;6180.81457556274;5863.1791549184;6034.879302414015;6216.906746398718;6341.619502829845;6365.809025729235;5853.473187413153;5853.9755542780695;5856.677882716321;5857.26784151981;5861.9706901466925;5975.492083172979;5978.581499295007;5978.850528736642;5981.880362392815;5983.850403739767;5624.437556244507;5732.799352022659;5838.795642152504;5946.048632218824;5959.582829201914;5869.7945571904775;5870.735816888165;5877.853053673693;5911.527077833677;5929;5570.972145139411;5829.242198514166;5834.533237341087;5836.503897168788;5838.357780644041;5804.245448091721;5843.912341314799;5902.2327097477055;5926.881462370815;5933.05071188625;5793.362730099601;5888.116782482662;5896.6756828374855;5906.616069955439;5914.083317085859 07:05:52 INFO - 2016-02-10 07:05:52,382 DEBUG : BROWSER_OUTPUT: |1;ext.html;610.3377835048972;619.3806193806194;619.6839611797982;653.1639501438159;665.7237246542686;41510.79244603776;42109.578904210946;42119;42121.15757684845;42136.78931605338;45130.54869451305;45160.322595161015;45304.32043519342;45678;45884.54115458844;7993.720219792309;8069.031716194065;8074.555899425529;9072.63709451621;9277.536123193848;10532.947335263312;10651.307665001788;10705;10713;10717.785644287109;10782.46087695616;10877.67366978992;10891.346519208873;10898.237123401364;10900.618478353259;10672.466376681174;10792.190585706103;10821.783564328709;10822.026017658449;10831;10580.523876425503;10647.414392208426;10716.035556799847;10723.946380268128;10732.35605863643;42216;42531.7873410631;42638.57361426377;42677.78661106706;42692.78653606712;3554.8400321985314;3739.102615372291;3774.75464094833;3813.180166264239;3849.056981039635;31702.365952681048;31835.204119896956;32123.196920076953;32183.6781632183;32966.34067318664;4204.957950420488;4230.91538169238;4242.580185458247;4259.16946195492;4260.637845783112;308746;311876;311889.44055279804;313159;313209;59024.40975590275;61091;68694;69077.30922690709;70496.64751676208;65460.34539654638;66369;66713.6664316685;66770.33229667641;66804.66597667077;17250.827491725173;17386.21762020712;17403.73894391585;17415.825841741673;17420.912895435442;13007.739845203043;13023.283719395524;13041.217526948414;13046.804297935538;13084.934575327254;22273.331800046024;22422;22424.439389015406;22497.662535061987;22580.758058306623;8345.332373410234;8517.403781735218;8628.536316086665;8637.996223990101;8731.388802783744;1025.702546261576;1085.5820509103894;1104.779044191165;1168.193946177135;1177.6349331707197;30265.848670756506;30833.38333233323;31280;31352.21619459486;31631.36737265288;1113.2596823112701;1125.1561329003248;1151.3609946479617;1167.217963964146;1167.3929556630505;14680.632984175267;15024.624384390478;15738.449154279546;17888.373906913195;18064.81935180658;425513;429557;431348;431558;432270;60701.39298607046;60880.391196087476;61048.38951610427;61180.38819611747;61477;1473.5800296915543;1478.6746915678534;1483.265783437184;1500.1224283793886;1511.05559025609;32016;32201.35597287994;32224.516632251005;32718.709690321448;33749.156271093416;46203.53796461992;46731.29903051525;50611.49388506068;51682.74158629183;52711.736441317546;229359;229633;230155;232158;234624;35392.11519712028;35640.465393019644;35649;35914.28171436609;35993.820030899675;46606.53393466022;47817.28274075961;48334.27498587454;48653.5134648649;48676.5132348672;38418.80790596029;39055.804720976215;39092.218155637296;46287.30569041534;46492;40173.1965360697;40442.39336410015;40632.968351582895;40742.59257407388;40759.796201018806;39015.6098439012;39770.2045959085;39889;39961.2007759849;39968;34621.48067778935;34715.30569388648;35476.290474190886;35819.28361432705;36451.63548364482;27997.099260907995;28459.43081138407;30200.84899575488;30231.395372091996;30843.537346940262 07:05:52 INFO - 2016-02-10 07:05:52,382 DEBUG : BROWSER_OUTPUT: |2;jquery.html;381.9809009549522;384.6634195079305;385.2891415338699;385.6953007124372;388.9572147063823;15188.924055379735;16161;17405.21676524556;18921.526961825963;18997.43007709769;19198.136083876223;19450.1247443865;19742;19947.60104797903;20050.197992080335;6026.126211699301;6201.751929922798;6227.159333489989;6237.002079667254;6237.625742455444;4641.582257596816;4701.694389864648;5399.62202645815;6254.155688981998;7065.717371305167;6537.93462065378;6986.161660600735;7044.718211271543;7045.3306935841265;7050;6670.7665231716655;6712.060311556386;6732.764353247638;6738.022986666906;6758.966205168992;6819.693113809891;6835.487338449636;6839.658017099175;6841.897371539431;6846.418054465376;18067.54831129232;18486.16812243439;18489.907550462296;18511.444656660322;21773.2379366723;2516.094206085808;2707.2284398946204;2752.6972033076345;2752.9036483723175;2764.8202866813594;15509;15517.767233491506;15607.687846243125;15850.841491585166;16070.116143612096;1910.1022519415853;1969.1926310212743;2057.1565658080267;2112.091800525773;2137.7541582717877;60512.394876051556;61359.38640613581;61466.385336146515;70754.64622676854;78626.6068669653;17041.48875533736;17501.212445439985;17514.211860466054;17542.386016489618;17546;27420;27609.723902761118;27705.86147069252;28005.299867503476;28090.85954570214;10467.267291289689;10561.207909406825;10577.735556610993;10595.258331916692;10605.628802991858;8639.438436501732;8648.221660050625;8670.783230419163;8674.86987695185;8969.192772650493;7248.637568121679;8559.871601925975;8561;8611.569421528899;8637.784055398666;2725.4004119093765;3137.262743255333;3227.483602623569;3298.884539041122;3354.1279267390655;429.10745649049903;433.15534707320853;468.55487287077415;996.2577879479834;1049.4700176410863;10214.182865370753;10499.842502362471;10692;11203.383813890314;11215.66353009411;469.8637395155367;477.06257204592447;477.44377799863463;480.4378876714183;481.9397575303087;6023.728932198062;6121.449069583669;6150.477209437248;6163.3528479509905;6221.626702397871;633385;655334;659728;667988;674699;84291.15708842955;86466.1353386458;86508;87786.56106719426;88389.55805220932;494.58207814396775;498.74813219324733;499.54042281101476;500.36453703795974;501.0680134948992;10743.140548755924;10778.568857245937;10789.651293588302;10947.890521095005;10990.340579565252;37384;37909.241815164096;38003.42994855135;38726.6127338723;38852;49905;51963.740181298854;53962.73018635039;54948.45051549433;55563.44436555583;9683.080107389787;9961.40231586107;10057.346272492265;10197.643082492077;10211.795764084825;10684.679459616224;13679.580104973757;18582;18588.721169182743;19193.232270709017;15569.221538922782;15649.530514084596;15689.607759806097;15732.977356471793;15767.290471928562;15049.990530209036;15232.086074835544;15414.152221627917;15492.457763978207;15835.762463563286;15896.364145434516;15916.0450372978;16163.030218186947;16411.261493232596;16473.752893706845;10033.849492257768;10226.948865255627;10393.272470927264;10395.740106497398;10412.947935260276;10933.617323393642;14937.402503899722;15163.241837907839;15386.69226615439;15518.612034699223 07:05:52 INFO - 2016-02-10 07:05:52,382 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1557.1902610642467;1564.1631727026036;1616.062683643487;1791.265581111744;1833.8349548540632;7757.534547927128;7951.840963180733;7961.4825036372595;7964.721234756785;7968.1235064142975;6683.866322673543;6882.380585747282;7486.139094004177;7712;7788.883166752503;2292.644640080786;2306.630939049752;2319.5013072189477;2326.5695846268495;2328.988355058222;2005.1878989009401;2299.183789754638;2328.5692146952783;2344.847584906984;2348.729896061949;1953.1308567687386;2227.9331620051425;2295.735990361105;2300.769923007694;2301.4016355747544;2256.4697296135396;2280.612295909691;2287.5424915017065;2333.265021518216;2333.9649905251435;2294.4722713775923;2307.4462129088897;2326.6044772388564;2334.9533009339716;2337.0418128567208;7743.457957942946;7760.611969401507;7791.1040230373665;7828.178041305695;7828.452008359417;4559.17934771741;4669.719816810968;4671;4674.088552732219;4679.625629949596;2918.1537354167476;3001.86491607876;3012.0963710886645;3039.6200474940633;3043.6651968283472;987.9753006174831;999.6501224571415;1052.100454111731;1082.2965072702727;1104.8729396119468;10426.5829366826;10427.217958653055;10460.581576736922;10484.947575262075;10491.318064325795;4001.379786133154;4061.614146656063;4064.5122585289364;4072.592740725963;4115.053537686349;9018.413803102778;9032.36773425854;9036.141566551167;9052.637894484147;9058.682946096855;4515.164694531522;4569.977150114228;4575.405197324393;4586.701864378805;4596.908061838744;4129.587041295847;4151.958480415217;4163.625273725379;4165.062860856283;4182.832686692498;3801.4107813288983;3866.8066596670615;3872.2642697887873;3886.028492876781;3898.941515877264;1098.741795678015;1170.303669316755;1231.4335405713473;1244.153975296807;1266.341502418737;1033.6330602636106;1170.2276497511602;1204.8915597596258;1367.453018792471;1473.1971075763734;7390.704371825174;7403.666834992438;7440.925590744023;7538.283863033003;7539;1203.6569577670323;1209.3167360441498;1214.8967337776212;1220.0605533738972;1235.5552001279532;5399.487048730364;5416.864578385572;5429.837104886859;5431.049566325956;5456.972715136479;9016.053314402025;9029.322800789967;9200.861987070199;9323.067693230623;9328.813423731488;9100.954495227481;9203.40177888435;9207.585658645377;9209.907900920905;9224;6938.444924406035;7066.470014748915;7287.781366559012;7303.45224108194;7309.086364204474;7306.4885458017425;7405.851882962418;7406.592637404994;7427.22014188524;7428.70285188608;8366.330693544624;8389.538575378412;8419.747407577783;8446.408751387344;8574.31405487547;8534.103919088655;8584.098669639849;8730.039695633603;8730.852844898622;8733.08302628215;7027.086478757728;7094.361507464353;7119.928800711927;7138.714451422093;7172.318629730167;7617.771466856003;7656.119546252252;7674.424418168548;7676.347510461674;7694.230576942261;6310.6213627182515;6969.756058537927;7066.787996360117;7104.857902841811;7135.82160445993;6454.548181627241;6511.511636627177;7003.649817509002;7008.544444611084;7200.207977122408;7201.279872012757;7205.459590530626;7243.23945985685;7250.492465527363;7292.96353518229;6647.900281495685;6681.432078273401;6683;6692;6703.430208432338;6359.268684101203;6366.618002919654;6376.808695739135;6385.584936979081;6386.457151142205 07:05:52 INFO - 2016-02-10 07:05:52,383 DEBUG : BROWSER_OUTPUT: |4;prototype.html;332.43983887150137;343.9329330780496;344.4953143644561;348.1487762420881;351.1046830582014;6032.638041717494;6112.480439162671;6129.969350153254;6135.509159267259;6152.876942461148;6042.184305118808;6047.274327080745;6047.818565443033;6066.787662431805;6076.665783381913;4493.5506449355125;4697.765111744399;4700.365450664168;4702.412198475191;4705.129551033053;4638.605718513913;4640.234361330394;4647.000894807616;4656.697314674535;4661.720296782187;4615.353850460938;4616.215243408612;4616.746078965655;4621.537846215385;4626.537346265363;4314.223439780838;4421.889452763675;4494.415725955605;4602.102589994953;4636.675432719711;3995.360742281221;4000.559938406802;4003.73975691582;4007.3788562772816;4016.4376987221813;5567.972160139214;5730.4269573042775;5743.310802703702;5758.62568933018;5762.567807414419;5117.923231151536;5254.106801843657;5341.946580534222;5344.91982620261;5345.732713364355;6348.714307856158;6363.649999250039;6374.904376434357;6375.585586936881;6377.075324077982;4419.867403977886;4423.955760442386;4426.933595996063;4427.048184640318;4432.889177770549;6523.673816309166;6592.439642630382;6652.634105124216;6653.500987425914;6677.031830384615;4561.270196768567;4563.133004729091;4565.132624801277;4567.543245675406;4567.725936443824;4607.470140933836;4622.352870598119;4623.653226007996;4625.051864367797;4626.676132670748;5334.706591137525;5336.252924590561;5345.1447768356875;5346.572274218053;5352.65207761502;6305.873882522324;6411.711472983727;6464.967675161594;6467.353264673589;6479.157709497735;1572.3003263547612;1631.8123415807097;1632.6571420001933;1634.8038235411827;1635.492997170881;1058.718950070408;1086.896744809242;1087.3475914451267;1101.5758932810925;1112.866456025282;6308.589941653778;6312.400321969406;6315.621062736159;6319.146915166486;6325.019621958604;6674.799756007327;6674.933250667528;6677.899831502531;6678.432333251728;6678.4991125665765;6181.660008699475;6318.399752023551;6322;6328.651924144124;6329.841753956188;3581;3603.279344131184;3607.819609019591;3610.0072480068193;3610.332088563624;6858.794236172923;6860.142482189726;6864.279250678706;6871.4502839773695;6874;6544.083828264047;6677.131972843499;6728.360805723449;6802.523823332418;6831.11195544576;6918.654067296615;6926.792196234121;6951.826204344932;6957.234704182637;6965.721371145098;6633.6019838809825;6664.733410663519;6668.966655166693;6675;6714.462842972648;6459.515536334699;6634.568753031038;6641.734330626721;6644.501662375438;6652.900206497004;6898.586084834926;6933;6936.861262774615;6940.41006514452;6942.1669399670345;4630.652701047367;4630.699004564693;4631.768411579475;4633.606143477842;4634.83778067766;6485.0596663484475;6695.564788288746;6705.597664140168;6719.697613607497;6730.46156307504;6562.212534495891;6564.343565643398;6568.671566421755;6573.80278591643;6576.8355791105605;6513.869722605426;6526.804195874131;6528.934710653023;6529.314421985813;6530.8367290818105;6198.876022479435;6351.587146835441;6360.936390636034;6440.710168042543;6449.193850768654;6565.737370505126;6568.080468734285;6568.1789776277965;6577.6053436793945;6578.276389597045;6408.839779005562;6409.3270206629495;6416.230052393743;6416.807495775134;6427.55007149495 07:05:52 INFO - 2016-02-10 07:05:52,384 DEBUG : BROWSER_OUTPUT: |5;yui.html;906.614688757278;912.3339961827866;955.2978560757842;955.8661787349772;957.6696039866246;14367.066140700861;14789.482368117091;14805.629859253511;14814.111153330781;14832.258387080657;13858.930705346484;13873.791893121586;13907.930460347709;13927.93036034821;13967;915.8763566918481;932.9766755831091;933.9299552533553;934.3039435620473;934.9018353072931;931.0875342164683;934.313279739391;937.4141161773891;938.0713094036888;938.2962777916563;968.9224862011022;970.3886551472563;975.3757595139081;975.7560609847538;975.868257785197;966.0580933589764;966.9854952175688;966.9854952175723;968.2737946540095;973.464594473044;712.6757325416922;713.8536599996988;716.3373879161754;716.7706333973131;716.9462290328246;15021;15132.848671513255;15143.167125808077;15175.848241517553;15180.240987950558;11378.487968041458;12121.27272363661;12137.332446715427;12692.365381730877;12799.74400511994;10805.945970270177;10999.175061870392;11073.390963497004;11077.944610276978;11088.889111108867;3706.3143318485895;3993.900152496211;4003.2994226010333;4022.4971878515184;4049.7570145791346;43626.563734362564;44601.33098003532;46118.07763844738;48296.51703482955;52075.739621301655;9986.300958932805;10187.847182292124;10278.743031424274;10703.571857125628;10790.56837726482;19724;19841.107150178275;19871.701924471145;19877.403677889688;19878.801211987695;7929.762107136795;8021.3181879539725;8074.434789564792;8303.211194936472;8319.126491718403;4704.341392205094;4724.480307166209;4792;4854;4923.950760492349;3264.8041117533025;3275.11571875597;3282.081017315108;3287.589051368579;3302.8183449910007;559.4768891086846;560.8205374280191;566.832784328624;567.7473524281655;587.4536680886816;659.3110199841177;669.6149713914518;669.8928171492538;675.6250281093942;692.5567636712508;8525.275351595184;8895.4662720237;9788.804223915482;9797.510124493747;9962.900370996342;540.8695825724177;684.4079870911626;694.5797792335666;702.3047183288508;720.945929055323;4214.759622324081;4271.145770845843;4336.479622445327;4389.122175564899;4420.491643460979;57969.71015144981;59681;60271.69864150739;60471;60574;49038.75480622574;53310.200346994825;54217.72891135519;54286.72856635691;54591.45408545943;48991;49207;49415.50584494181;49484.257736133986;50271.74864125729;9572.76068098303;9610.711678649652;9688.75778105553;9765;10031.548580313758;38718.41922371223;39683.60316396799;40782.79608601938;41082;41360;47143.292850607955;47341.76329118332;47709.28436073531;47828.04343913033;48025.27962080642;11323.660290191307;11605.535778568763;11687.532498699957;11713.238639488405;12103;12849.614511564669;12932.353382330657;13020.869791302344;13183;13222.735545288848;11399.943000284946;11515.539378425105;11544.01875840563;11718.94140529326;11728;11337.149713771665;11394.544218231178;11417.37204453763;11541.480633371351;11738.237014594024;11694.824577631172;11856.34790086519;11967.581134660593;12230.327331996825;12354;9622.230221582116;9771.413715177112;9847.261455391017;9942.15491683215;10088.142507886912;9656.275779316438;10069.496525173858;10189.74525636865;10233.744156395851;10565.26043177001 07:05:52 INFO - 2016-02-10 07:05:52,384 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:05:52 INFO - 2016-02-10 07:05:52,384 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:05:52 INFO - 2016-02-10 07:05:52,384 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,796 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: __startTimestamp1455116752375__endTimestamp 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5587.15 Median:5831.94 stddev:630.38 (10.8%) stddev-sans-first:606.00 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: Values: 3289.6 3367.9 3384.6 3388.9 3406.1 5552.6 5573.8 5575.1 5576.1 5624.2 5587.2 5623.2 5628.5 5628.7 5632.3 4932.5 5028.6 5082.4 5104.7 5158.4 5081.6 5132.5 5137.2 5138.0 5139.1 5016.3 5089.7 5121.5 5155.6 5161.1 5130.1 5157.6 5162.6 5170.1 5195.2 4884.4 4939.6 5087.7 5089.8 5154.0 5245.9 5338.8 5351.8 5356.8 5359.2 6081.3 6086.5 6130.9 6135.5 6137.3 5677.0 5766.2 6031.6 6142.3 6152.0 5673.7 5676.1 5681.5 5683.9 5687.4 6139.4 6142.0 6146.2 6146.8 6149.1 5715.4 5832.2 5879.8 5939.7 5963.9 6005.5 6076.1 6076.1 6077.3 6082.2 5992.1 6031.4 6044.5 6045.9 6050.0 5673.4 5790.7 5854.8 5943.5 6046.1 4553.6 4598.2 4636.1 4637.8 4640.1 4157.6 4168.3 4173.3 4179.1 4211.3 5500.1 5523.1 5556.2 5652.8 5799.9 6107.9 6147.2 6155.0 6156.5 6161.7 5815.7 5831.9 5832.9 5833.2 5834.1 5027.7 5061.0 5124.3 5138.7 5290.4 6308.9 6318.4 6343.0 6352.0 6359.8 6173.2 6174.1 6174.4 6177.3 6180.8 5863.2 6034.9 6216.9 6341.6 6365.8 5853.5 5854.0 5856.7 5857.3 5862.0 5975.5 5978.6 5978.9 5981.9 5983.9 5624.4 5732.8 5838.8 5946.0 5959.6 5869.8 5870.7 5877.9 5911.5 5929.0 5571.0 5829.2 5834.5 5836.5 5838.4 5804.2 5843.9 5902.2 5926.9 5933.1 5793.4 5888.1 5896.7 5906.6 5914.1 07:05:52 INFO - 2016-02-10 07:05:52,385 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,387 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51499.11 Median:35644.73 stddev:87860.85 (246.5%) stddev-sans-first:88024.24 07:05:52 INFO - 2016-02-10 07:05:52,387 DEBUG : BROWSER_OUTPUT: Values: 610.3 619.4 619.7 653.2 665.7 41510.8 42109.6 42119.0 42121.2 42136.8 45130.5 45160.3 45304.3 45678.0 45884.5 7993.7 8069.0 8074.6 9072.6 9277.5 10532.9 10651.3 10705.0 10713.0 10717.8 10782.5 10877.7 10891.3 10898.2 10900.6 10672.5 10792.2 10821.8 10822.0 10831.0 10580.5 10647.4 10716.0 10723.9 10732.4 42216.0 42531.8 42638.6 42677.8 42692.8 3554.8 3739.1 3774.8 3813.2 3849.1 31702.4 31835.2 32123.2 32183.7 32966.3 4205.0 4230.9 4242.6 4259.2 4260.6 308746.0 311876.0 311889.4 313159.0 313209.0 59024.4 61091.0 68694.0 69077.3 70496.6 65460.3 66369.0 66713.7 66770.3 66804.7 17250.8 17386.2 17403.7 17415.8 17420.9 13007.7 13023.3 13041.2 13046.8 13084.9 22273.3 22422.0 22424.4 22497.7 22580.8 8345.3 8517.4 8628.5 8638.0 8731.4 1025.7 1085.6 1104.8 1168.2 1177.6 30265.8 30833.4 31280.0 31352.2 31631.4 1113.3 1125.2 1151.4 1167.2 1167.4 14680.6 15024.6 15738.4 17888.4 18064.8 425513.0 429557.0 431348.0 431558.0 432270.0 60701.4 60880.4 61048.4 61180.4 61477.0 1473.6 1478.7 1483.3 1500.1 1511.1 32016.0 32201.4 32224.5 32718.7 33749.2 46203.5 46731.3 50611.5 51682.7 52711.7 229359.0 229633.0 230155.0 232158.0 234624.0 35392.1 35640.5 35649.0 35914.3 35993.8 46606.5 47817.3 48334.3 48653.5 48676.5 38418.8 39055.8 39092.2 46287.3 46492.0 40173.2 40442.4 40633.0 40742.6 40759.8 39015.6 39770.2 39889.0 39961.2 39968.0 34621.5 34715.3 35476.3 35819.3 36451.6 27997.1 28459.4 30200.8 30231.4 30843.5 07:05:52 INFO - 2016-02-10 07:05:52,387 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33765.64 Median:3182.37 stddev:107444.23 (3376.2%) stddev-sans-first:107716.40 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: Values: 382.0 384.7 385.3 385.7 389.0 15188.9 16161.0 17405.2 18921.5 18997.4 19198.1 19450.1 19742.0 19947.6 20050.2 6026.1 6201.8 6227.2 6237.0 6237.6 4641.6 4701.7 5399.6 6254.2 7065.7 6537.9 6986.2 7044.7 7045.3 7050.0 6670.8 6712.1 6732.8 6738.0 6759.0 6819.7 6835.5 6839.7 6841.9 6846.4 18067.5 18486.2 18489.9 18511.4 21773.2 2516.1 2707.2 2752.7 2752.9 2764.8 15509.0 15517.8 15607.7 15850.8 16070.1 1910.1 1969.2 2057.2 2112.1 2137.8 60512.4 61359.4 61466.4 70754.6 78626.6 17041.5 17501.2 17514.2 17542.4 17546.0 27420.0 27609.7 27705.9 28005.3 28090.9 10467.3 10561.2 10577.7 10595.3 10605.6 8639.4 8648.2 8670.8 8674.9 8969.2 7248.6 8559.9 8561.0 8611.6 8637.8 2725.4 3137.3 3227.5 3298.9 3354.1 429.1 433.2 468.6 996.3 1049.5 10214.2 10499.8 10692.0 11203.4 11215.7 469.9 477.1 477.4 480.4 481.9 6023.7 6121.4 6150.5 6163.4 6221.6 633385.0 655334.0 659728.0 667988.0 674699.0 84291.2 86466.1 86508.0 87786.6 88389.6 494.6 498.7 499.5 500.4 501.1 10743.1 10778.6 10789.7 10947.9 10990.3 37384.0 37909.2 38003.4 38726.6 38852.0 49905.0 51963.7 53962.7 54948.5 55563.4 9683.1 9961.4 10057.3 10197.6 10211.8 10684.7 13679.6 18582.0 18588.7 19193.2 15569.2 15649.5 15689.6 15733.0 15767.3 15050.0 15232.1 15414.2 15492.5 15835.8 15896.4 15916.0 16163.0 16411.3 16473.8 10033.8 10226.9 10393.3 10395.7 10412.9 10933.6 14937.4 15163.2 15386.7 15518.6 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5455.24 Median:6371.71 stddev:2795.55 (43.9%) stddev-sans-first:2788.03 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: Values: 1557.2 1564.2 1616.1 1791.3 1833.8 7757.5 7951.8 7961.5 7964.7 7968.1 6683.9 6882.4 7486.1 7712.0 7788.9 2292.6 2306.6 2319.5 2326.6 2329.0 2005.2 2299.2 2328.6 2344.8 2348.7 1953.1 2227.9 2295.7 2300.8 2301.4 2256.5 2280.6 2287.5 2333.3 2334.0 2294.5 2307.4 2326.6 2335.0 2337.0 7743.5 7760.6 7791.1 7828.2 7828.5 4559.2 4669.7 4671.0 4674.1 4679.6 2918.2 3001.9 3012.1 3039.6 3043.7 988.0 999.7 1052.1 1082.3 1104.9 10426.6 10427.2 10460.6 10484.9 10491.3 4001.4 4061.6 4064.5 4072.6 4115.1 9018.4 9032.4 9036.1 9052.6 9058.7 4515.2 4570.0 4575.4 4586.7 4596.9 4129.6 4152.0 4163.6 4165.1 4182.8 3801.4 3866.8 3872.3 3886.0 3898.9 1098.7 1170.3 1231.4 1244.2 1266.3 1033.6 1170.2 1204.9 1367.5 1473.2 7390.7 7403.7 7440.9 7538.3 7539.0 1203.7 1209.3 1214.9 1220.1 1235.6 5399.5 5416.9 5429.8 5431.0 5457.0 9016.1 9029.3 9200.9 9323.1 9328.8 9101.0 9203.4 9207.6 9209.9 9224.0 6938.4 7066.5 7287.8 7303.5 7309.1 7306.5 7405.9 7406.6 7427.2 7428.7 8366.3 8389.5 8419.7 8446.4 8574.3 8534.1 8584.1 8730.0 8730.9 8733.1 7027.1 7094.4 7119.9 7138.7 7172.3 7617.8 7656.1 7674.4 7676.3 7694.2 6310.6 6969.8 7066.8 7104.9 7135.8 6454.5 6511.5 7003.6 7008.5 7200.2 7201.3 7205.5 7243.2 7250.5 7293.0 6647.9 6681.4 6683.0 6692.0 6703.4 6359.3 6366.6 6376.8 6385.6 6386.5 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5399.48 Median:6190.27 stddev:1638.54 (26.5%) stddev-sans-first:1598.39 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: Values: 332.4 343.9 344.5 348.1 351.1 6032.6 6112.5 6130.0 6135.5 6152.9 6042.2 6047.3 6047.8 6066.8 6076.7 4493.6 4697.8 4700.4 4702.4 4705.1 4638.6 4640.2 4647.0 4656.7 4661.7 4615.4 4616.2 4616.7 4621.5 4626.5 4314.2 4421.9 4494.4 4602.1 4636.7 3995.4 4000.6 4003.7 4007.4 4016.4 5568.0 5730.4 5743.3 5758.6 5762.6 5117.9 5254.1 5341.9 5344.9 5345.7 6348.7 6363.6 6374.9 6375.6 6377.1 4419.9 4424.0 4426.9 4427.0 4432.9 6523.7 6592.4 6652.6 6653.5 6677.0 4561.3 4563.1 4565.1 4567.5 4567.7 4607.5 4622.4 4623.7 4625.1 4626.7 5334.7 5336.3 5345.1 5346.6 5352.7 6305.9 6411.7 6465.0 6467.4 6479.2 1572.3 1631.8 1632.7 1634.8 1635.5 1058.7 1086.9 1087.3 1101.6 1112.9 6308.6 6312.4 6315.6 6319.1 6325.0 6674.8 6674.9 6677.9 6678.4 6678.5 6181.7 6318.4 6322.0 6328.7 6329.8 3581.0 3603.3 3607.8 3610.0 3610.3 6858.8 6860.1 6864.3 6871.5 6874.0 6544.1 6677.1 6728.4 6802.5 6831.1 6918.7 6926.8 6951.8 6957.2 6965.7 6633.6 6664.7 6669.0 6675.0 6714.5 6459.5 6634.6 6641.7 6644.5 6652.9 6898.6 6933.0 6936.9 6940.4 6942.2 4630.7 4630.7 4631.8 4633.6 4634.8 6485.1 6695.6 6705.6 6719.7 6730.5 6562.2 6564.3 6568.7 6573.8 6576.8 6513.9 6526.8 6528.9 6529.3 6530.8 6198.9 6351.6 6360.9 6440.7 6449.2 6565.7 6568.1 6568.2 6577.6 6578.3 6408.8 6409.3 6416.2 6416.8 6427.6 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14590.63 Median:26033.12 stddev:16665.08 (64.0%) stddev-sans-first:16680.14 07:05:52 INFO - 2016-02-10 07:05:52,388 DEBUG : BROWSER_OUTPUT: Values: 906.6 912.3 955.3 955.9 957.7 14367.1 14789.5 14805.6 14814.1 14832.3 13858.9 13873.8 13907.9 13927.9 13967.0 915.9 933.0 933.9 934.3 934.9 931.1 934.3 937.4 938.1 938.3 968.9 970.4 975.4 975.8 975.9 966.1 967.0 967.0 968.3 973.5 712.7 713.9 716.3 716.8 716.9 15021.0 15132.8 15143.2 15175.8 15180.2 11378.5 12121.3 12137.3 12692.4 12799.7 10805.9 10999.2 11073.4 11077.9 11088.9 3706.3 3993.9 4003.3 4022.5 4049.8 43626.6 44601.3 46118.1 48296.5 52075.7 9986.3 10187.8 10278.7 10703.6 10790.6 19724.0 19841.1 19871.7 19877.4 19878.8 7929.8 8021.3 8074.4 8303.2 8319.1 4704.3 4724.5 4792.0 4854.0 4924.0 3264.8 3275.1 3282.1 3287.6 3302.8 559.5 560.8 566.8 567.7 587.5 659.3 669.6 669.9 675.6 692.6 8525.3 8895.5 9788.8 9797.5 9962.9 540.9 684.4 694.6 702.3 720.9 4214.8 4271.1 4336.5 4389.1 4420.5 57969.7 59681.0 60271.7 60471.0 60574.0 49038.8 53310.2 54217.7 54286.7 54591.5 48991.0 49207.0 49415.5 49484.3 50271.7 9572.8 9610.7 9688.8 9765.0 10031.5 38718.4 39683.6 40782.8 41082.0 41360.0 47143.3 47341.8 47709.3 47828.0 48025.3 11323.7 11605.5 11687.5 11713.2 12103.0 12849.6 12932.4 13020.9 13183.0 13222.7 11399.9 11515.5 11544.0 11718.9 11728.0 11337.1 11394.5 11417.4 11541.5 11738.2 11694.8 11856.3 11967.6 12230.3 12354.0 9622.2 9771.4 9847.3 9942.2 10088.1 9656.3 10069.5 10189.7 10233.7 10565.3 07:05:52 INFO - 2016-02-10 07:05:52,389 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:05:52 INFO - 2016-02-10 07:05:52,389 DEBUG : BROWSER_OUTPUT: 07:05:52 INFO - 2016-02-10 07:05:52,864 INFO : Browser exited with error code: 0 07:05:52 INFO - 2016-02-10 07:05:52,887 INFO : Completed test dromaeo_css (00:18:30) 07:05:52 INFO - 2016-02-10 07:05:52,887 INFO : Starting test kraken 07:05:52 INFO - 2016-02-10 07:05:52,887 DEBUG : operating with platform_type : linux_ 07:05:52 INFO - 2016-02-10 07:05:52,887 INFO : Initialising browser for kraken test... 07:05:52 INFO - 2016-02-10 07:05:52,894 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ZbnkX/profile http://localhost:60643/getInfo.html 07:05:56 INFO - 2016-02-10 07:05:56,632 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:05:56 INFO - 2016-02-10 07:05:56,633 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:05:56 INFO - 2016-02-10 07:05:56,633 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:05:56 INFO - 2016-02-10 07:05:56,633 DEBUG : BROWSER_OUTPUT: __metrics 07:05:56 INFO - 2016-02-10 07:05:56,818 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] 07:05:57 INFO - 2016-02-10 07:05:57,648 INFO : Browser initialized. 07:05:57 INFO - 2016-02-10 07:05:57,648 INFO : Running cycle 1/1 for kraken test... 07:05:57 INFO - 2016-02-10 07:05:57,649 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp4ZbnkX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:06:09 INFO - 2016-02-10 07:06:09,321 DEBUG : BROWSER_OUTPUT: RSS: Main: 132161536 07:06:09 INFO - 2016-02-10 07:06:09,321 DEBUG : BROWSER_OUTPUT: 07:06:11 INFO - 2016-02-10 07:06:11,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60643/tests/kraken/driver.html?testName=audio-beat-detection) 07:06:12 INFO - 2016-02-10 07:06:12,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 166027264 07:06:12 INFO - 2016-02-10 07:06:12,343 DEBUG : BROWSER_OUTPUT: 07:06:14 INFO - 2016-02-10 07:06:14,939 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60643/tests/kraken/driver.html?testName=audio-dft) 07:06:15 INFO - 2016-02-10 07:06:15,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 163127296 07:06:15 INFO - 2016-02-10 07:06:15,506 DEBUG : BROWSER_OUTPUT: 07:06:18 INFO - 2016-02-10 07:06:18,313 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60643/tests/kraken/driver.html?testName=audio-fft) 07:06:18 INFO - 2016-02-10 07:06:18,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 159092736 07:06:18 INFO - 2016-02-10 07:06:18,879 DEBUG : BROWSER_OUTPUT: 07:06:20 INFO - 2016-02-10 07:06:20,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60643/tests/kraken/driver.html?testName=audio-oscillator) 07:06:21 INFO - 2016-02-10 07:06:21,405 DEBUG : BROWSER_OUTPUT: RSS: Main: 159494144 07:06:21 INFO - 2016-02-10 07:06:21,405 DEBUG : BROWSER_OUTPUT: 07:06:23 INFO - 2016-02-10 07:06:23,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60643/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:06:24 INFO - 2016-02-10 07:06:24,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 159965184 07:06:24 INFO - 2016-02-10 07:06:24,133 DEBUG : BROWSER_OUTPUT: 07:06:26 INFO - 2016-02-10 07:06:26,999 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60643/tests/kraken/driver.html?testName=imaging-darkroom) 07:06:27 INFO - 2016-02-10 07:06:27,570 DEBUG : BROWSER_OUTPUT: RSS: Main: 159678464 07:06:27 INFO - 2016-02-10 07:06:27,570 DEBUG : BROWSER_OUTPUT: 07:06:30 INFO - 2016-02-10 07:06:30,892 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60643/tests/kraken/driver.html?testName=imaging-desaturate) 07:06:31 INFO - 2016-02-10 07:06:31,458 DEBUG : BROWSER_OUTPUT: RSS: Main: 159576064 07:06:31 INFO - 2016-02-10 07:06:31,458 DEBUG : BROWSER_OUTPUT: 07:06:34 INFO - 2016-02-10 07:06:34,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60643/tests/kraken/driver.html?testName=json-parse-financial) 07:06:34 INFO - 2016-02-10 07:06:34,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 160329728 07:06:34 INFO - 2016-02-10 07:06:34,744 DEBUG : BROWSER_OUTPUT: 07:06:36 INFO - 2016-02-10 07:06:36,299 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60643/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:06:36 INFO - 2016-02-10 07:06:36,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 163921920 07:06:36 INFO - 2016-02-10 07:06:36,862 DEBUG : BROWSER_OUTPUT: 07:06:38 INFO - 2016-02-10 07:06:38,593 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:06:39 INFO - 2016-02-10 07:06:39,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 162119680 07:06:39 INFO - 2016-02-10 07:06:39,195 DEBUG : BROWSER_OUTPUT: 07:06:41 INFO - 2016-02-10 07:06:41,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:06:41 INFO - 2016-02-10 07:06:41,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 167923712 07:06:41 INFO - 2016-02-10 07:06:41,725 DEBUG : BROWSER_OUTPUT: 07:06:44 INFO - 2016-02-10 07:06:44,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:06:44 INFO - 2016-02-10 07:06:44,665 DEBUG : BROWSER_OUTPUT: RSS: Main: 175091712 07:06:44 INFO - 2016-02-10 07:06:44,666 DEBUG : BROWSER_OUTPUT: 07:06:47 INFO - 2016-02-10 07:06:47,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:06:47 INFO - 2016-02-10 07:06:47,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 174546944 07:06:47 INFO - 2016-02-10 07:06:47,722 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,372 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60643/tests/kraken/driver.html?testName=ai-astar) 07:06:49 INFO - 2016-02-10 07:06:49,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 232878080 07:06:49 INFO - 2016-02-10 07:06:49,618 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,621 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:06:49 INFO - 2016-02-10 07:06:49,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:06:49 INFO - 2016-02-10 07:06:49,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;104;110;111;110;99;109;110;110 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;121;149;166;124;152;153;123;168;123 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;160;160;159;160;161;163;160;160 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;73;148;73;71;70;71;151;70 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;94;132;93;93;93;138;93;94;134 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;110;110;109;109;111;109 07:06:49 INFO - 2016-02-10 07:06:49,622 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;145;146;146;146;146;145;145;144 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;95;95;95;95;95;96;95 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;64;59;57;58;58;56;53;56 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;63;63;65;64;64;64;65;64 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;77;77;78;81;80;80;79;77 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;111;117;110;121;121;115;119;113;112 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;129;143;140;142;142;134;151;138;141;141 07:06:49 INFO - 2016-02-10 07:06:49,623 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;62;67;62;66;64;64;65;62;62 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,946 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: __startTimestamp1455116809619__endTimestamp 07:06:49 INFO - 2016-02-10 07:06:49,624 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,628 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:06:49 INFO - 2016-02-10 07:06:49,628 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:06:49 INFO - 2016-02-10 07:06:49,628 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,628 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.30 Median:110.00 stddev:3.80 (3.5%) stddev-sans-first:3.98 07:06:49 INFO - 2016-02-10 07:06:49,629 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 104.0 110.0 111.0 110.0 99.0 109.0 110.0 110.0 07:06:49 INFO - 2016-02-10 07:06:49,629 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,629 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.70 Median:150.50 stddev:18.38 (12.2%) stddev-sans-first:19.39 07:06:49 INFO - 2016-02-10 07:06:49,629 DEBUG : BROWSER_OUTPUT: Values: 148.0 121.0 149.0 166.0 124.0 152.0 153.0 123.0 168.0 123.0 07:06:49 INFO - 2016-02-10 07:06:49,630 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,630 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 07:06:49 INFO - 2016-02-10 07:06:49,630 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 160.0 160.0 159.0 160.0 161.0 163.0 160.0 160.0 07:06:49 INFO - 2016-02-10 07:06:49,630 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,631 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.00 Median:71.00 stddev:32.96 (46.4%) stddev-sans-first:34.51 07:06:49 INFO - 2016-02-10 07:06:49,631 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 73.0 148.0 73.0 71.0 70.0 71.0 151.0 70.0 07:06:49 INFO - 2016-02-10 07:06:49,631 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,631 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.60 Median:93.00 stddev:20.12 (21.6%) stddev-sans-first:20.73 07:06:49 INFO - 2016-02-10 07:06:49,631 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 132.0 93.0 93.0 93.0 138.0 93.0 94.0 134.0 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 110.0 110.0 109.0 109.0 111.0 109.0 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.84 (0.6%) stddev-sans-first:0.73 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 144.0 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 07:06:49 INFO - 2016-02-10 07:06:49,632 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:2.87 (5.0%) stddev-sans-first:3.00 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 64.0 59.0 57.0 58.0 58.0 56.0 53.0 56.0 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 63.0 63.0 65.0 64.0 64.0 64.0 65.0 64.0 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:1.51 (1.9%) stddev-sans-first:1.59 07:06:49 INFO - 2016-02-10 07:06:49,633 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 77.0 77.0 78.0 81.0 80.0 80.0 79.0 77.0 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.70 Median:117.50 stddev:4.08 (3.5%) stddev-sans-first:4.25 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: Values: 118.0 111.0 117.0 110.0 121.0 121.0 115.0 119.0 113.0 112.0 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.10 Median:141.50 stddev:5.78 (4.1%) stddev-sans-first:4.53 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: Values: 129.0 143.0 140.0 142.0 142.0 134.0 151.0 138.0 141.0 141.0 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: 07:06:49 INFO - 2016-02-10 07:06:49,634 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.81 (2.8%) stddev-sans-first:1.92 07:06:49 INFO - 2016-02-10 07:06:49,635 DEBUG : BROWSER_OUTPUT: Values: 64.0 62.0 67.0 62.0 66.0 64.0 64.0 65.0 62.0 62.0 07:06:49 INFO - 2016-02-10 07:06:49,636 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:06:49 INFO - 2016-02-10 07:06:49,636 DEBUG : BROWSER_OUTPUT: 07:06:50 INFO - 2016-02-10 07:06:50,260 INFO : Browser exited with error code: 0 07:06:50 INFO - 2016-02-10 07:06:50,316 INFO : Completed test kraken (00:00:57) 07:06:50 INFO - 2016-02-10 07:06:50,316 INFO : Starting test v8_7 07:06:50 INFO - 2016-02-10 07:06:50,316 DEBUG : operating with platform_type : linux_ 07:06:50 INFO - 2016-02-10 07:06:50,316 INFO : Initialising browser for v8_7 test... 07:06:50 INFO - 2016-02-10 07:06:50,322 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpF38ERi/profile http://localhost:60643/getInfo.html 07:06:53 INFO - 2016-02-10 07:06:53,841 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:06:53 INFO - 2016-02-10 07:06:53,841 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:06:53 INFO - 2016-02-10 07:06:53,841 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:06:53 INFO - 2016-02-10 07:06:53,841 DEBUG : BROWSER_OUTPUT: __metrics 07:06:53 INFO - 2016-02-10 07:06:53,982 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] 07:06:55 INFO - 2016-02-10 07:06:55,124 INFO : Browser initialized. 07:06:55 INFO - 2016-02-10 07:06:55,124 INFO : Running cycle 1/1 for v8_7 test... 07:06:55 INFO - 2016-02-10 07:06:55,125 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpF38ERi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:07:06 INFO - 2016-02-10 07:07:06,810 DEBUG : BROWSER_OUTPUT: RSS: Main: 132292608 07:07:06 INFO - 2016-02-10 07:07:06,811 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,142 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60643/tests/v8_7/run.html (next: http://localhost:60643/tests/v8_7/run.html) 07:07:28 INFO - 2016-02-10 07:07:28,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 430518272 07:07:28 INFO - 2016-02-10 07:07:28,388 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,389 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:07:28 INFO - 2016-02-10 07:07:28,389 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |0;Richards;171.49717029669011 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;168.9189189189189 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 07:07:28 INFO - 2016-02-10 07:07:28,390 DEBUG : BROWSER_OUTPUT: |4;RayTrace;943.3962264150944 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: |5;Earley;544.9591280653951 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: |6;Boyer;10568.421052631578 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: |7;RegExp;26500 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: |8;Splay;514.668039114771 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:07:28 INFO - 2016-02-10 07:07:28,391 DEBUG : BROWSER_OUTPUT: __startTimestamp1455116848388__endTimestamp 07:07:28 INFO - 2016-02-10 07:07:28,392 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,392 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:07:28 INFO - 2016-02-10 07:07:28,392 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:07:28 INFO - 2016-02-10 07:07:28,392 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,392 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.50 Median:171.50 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,393 DEBUG : BROWSER_OUTPUT: Values: 171.5 07:07:28 INFO - 2016-02-10 07:07:28,393 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,394 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:168.92 Median:168.92 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,394 DEBUG : BROWSER_OUTPUT: Values: 168.9 07:07:28 INFO - 2016-02-10 07:07:28,394 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: Values: 278.4 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: Values: 5133.3 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:943.40 Median:943.40 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,395 DEBUG : BROWSER_OUTPUT: Values: 943.4 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.96 Median:544.96 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: Values: 545.0 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10568.42 Median:10568.42 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: Values: 10568.4 07:07:28 INFO - 2016-02-10 07:07:28,396 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26500.00 Median:26500.00 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: Values: 26500.0 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:514.67 Median:514.67 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: Values: 514.7 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 07:07:28 INFO - 2016-02-10 07:07:28,397 DEBUG : BROWSER_OUTPUT: Values: 4835.7 07:07:28 INFO - 2016-02-10 07:07:28,398 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:07:28 INFO - 2016-02-10 07:07:28,398 DEBUG : BROWSER_OUTPUT: 07:07:29 INFO - 2016-02-10 07:07:29,064 INFO : Browser exited with error code: 0 07:07:29 INFO - 2016-02-10 07:07:29,078 INFO : Completed test v8_7 (00:00:38) 07:07:29 INFO - 2016-02-10 07:07:29,467 INFO : Completed test suite (00:20:06) 07:07:29 INFO - 2016-02-10 07:07:29,467 DEBUG : Working with test: dromaeo_css 07:07:29 INFO - 2016-02-10 07:07:29,468 DEBUG : Generating results file: dromaeo_css 07:07:29 INFO - 2016-02-10 07:07:29,469 DEBUG : Working with test: kraken 07:07:29 INFO - 2016-02-10 07:07:29,469 DEBUG : Generating results file: kraken 07:07:29 INFO - 2016-02-10 07:07:29,469 INFO : javascript benchmark 07:07:29 INFO - 2016-02-10 07:07:29,469 DEBUG : Working with test: v8_7 07:07:29 INFO - 2016-02-10 07:07:29,469 DEBUG : Generating results file: v8_7 07:07:29 INFO - 2016-02-10 07:07:29,470 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:29 INFO - 2016-02-10 07:07:29,534 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 07:07:29 INFO - 2016-02-10 07:07:29,534 DEBUG : process_Request line: dromaeo_css 7395.65 graph.html#tests=[[72,53,35]] 07:07:29 INFO - 2016-02-10 07:07:29,534 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:29 INFO - 2016-02-10 07:07:29,574 DEBUG : process_Request line: kraken 1473.80 graph.html#tests=[[232,53,35]] 07:07:29 INFO - 2016-02-10 07:07:29,575 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:07:29 INFO - 2016-02-10 07:07:29,611 DEBUG : process_Request line: v8_7 23032.93 graph.html#tests=[[230,53,35]] 07:07:29 INFO - 2016-02-10 07:07:29,612 INFO : javascript benchmark 07:07:29 INFO - 2016-02-10 07:07:29,616 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3289.6052473703157, 3367.89896303111, 3384.644612315705, 3388.932221355573, 3406.148462884279, 5552.583556233283, 5573.804916827912, 5575.080111781556, 5576.052071147905, 5624.21261023457, 5587.245721827552, 5623.156526521024, 5628.465295796903, 5628.71856407179, 5632.295963004624, 4932.506749325057, 5028.647994640377, 5082.364704411958, 5104.719240441774, 5158.380994280692, 5081.618878584102, 5132.538071573557, 5137.229415587673, 5137.974310128444, 5139.126348520742, 5016.297718319438, 5089.669171503859, 5121.513456221672, 5155.613329000321, 5161.0968080585935, 5130.127878260705, 5157.5616072633875, 5162.6128040397125, 5170.121079416508, 5195.194744814522, 4884.365032545782, 4939.604831613498, 5087.669301495428, 5089.770960306795, 5154.046501397216, 5245.868853278698, 5338.813141540067, 5351.759170837322, 5356.758945847446, 5359.22291267764, 6081.3006504252135, 6086.452219300284, 6130.908036379458, 6135.478484328837, 6137.324894261627, 5677.0, 5766.163906233614, 6031.577789554689, 6142.324344322121, 6152.0, 5673.716314184274, 5676.091825307931, 5681.460261275213, 5683.914741278884, 5687.374388817227, 6139.447449729457, 6141.96929015361, 6146.200993870768, 6146.754129834846, 6149.13912052313, 5715.399883012307, 5832.212651292021, 5879.764809407576, 5939.70301484924, 5963.910541341883, 6005.549583781234, 6076.088586712029, 6076.149339092531, 6077.270727512727, 6082.239720034996, 5992.101184822224, 6031.42701443371, 6044.546659000593, 6045.909311360333, 6050.0, 5673.404292549223, 5790.681512516768, 5854.765809367577, 5943.494802941712, 6046.062860256668, 4553.612942899825, 4598.2412901871485, 4636.142313671914, 4637.837675681402, 4640.071985602893, 4157.625813676784, 4168.333066709312, 4173.311403618369, 4179.101493178981, 4211.284081706117, 5500.064988951888, 5523.061079616475, 5556.16657501378, 5652.773889044474, 5799.9130013049835, 6107.877842443127, 6147.231596050494, 6155.0, 6156.476699480505, 6161.661108639068, 5815.73829177688, 5831.94168058314, 5832.854178645568, 5833.183354330397, 5834.066549352084, 5027.673201241908, 5060.974695126501, 5124.308218390544, 5138.665986710852, 5290.365156181206, 6308.905366419507, 6318.4313411793155, 6343.0, 6351.96824015877, 6359.841003974845, 6173.166622505904, 6174.073888916699, 6174.413430724074, 6177.289611694712, 6180.81457556274, 5863.1791549184, 6034.879302414015, 6216.906746398718, 6341.619502829845, 6365.809025729235, 5853.473187413153, 5853.9755542780695, 5856.677882716321, 5857.26784151981, 5861.9706901466925, 5975.492083172979, 5978.581499295007, 5978.850528736642, 5981.880362392815, 5983.850403739767, 5624.437556244507, 5732.799352022659, 5838.795642152504, 5946.048632218824, 5959.582829201914, 5869.7945571904775, 5870.735816888165, 5877.853053673693, 5911.527077833677, 5929.0, 5570.972145139411, 5829.242198514166, 5834.533237341087, 5836.503897168788, 5838.357780644041, 5804.245448091721, 5843.912341314799, 5902.2327097477055, 5926.881462370815, 5933.05071188625, 5793.362730099601, 5888.116782482662, 5896.6756828374855, 5906.616069955439, 5914.083317085859], "unit": "ms", "name": "dojo.html", "value": 5546.184641097073}, {"lowerIsBetter": false, "replicates": [610.3377835048972, 619.3806193806194, 619.6839611797982, 653.1639501438159, 665.7237246542686, 41510.79244603776, 42109.578904210946, 42119.0, 42121.15757684845, 42136.78931605338, 45130.54869451305, 45160.322595161015, 45304.32043519342, 45678.0, 45884.54115458844, 7993.720219792309, 8069.031716194065, 8074.555899425529, 9072.63709451621, 9277.536123193848, 10532.947335263312, 10651.307665001788, 10705.0, 10713.0, 10717.785644287109, 10782.46087695616, 10877.67366978992, 10891.346519208873, 10898.237123401364, 10900.618478353259, 10672.466376681174, 10792.190585706103, 10821.783564328709, 10822.026017658449, 10831.0, 10580.523876425503, 10647.414392208426, 10716.035556799847, 10723.946380268128, 10732.35605863643, 42216.0, 42531.7873410631, 42638.57361426377, 42677.78661106706, 42692.78653606712, 3554.8400321985314, 3739.102615372291, 3774.75464094833, 3813.180166264239, 3849.056981039635, 31702.365952681048, 31835.204119896956, 32123.196920076953, 32183.6781632183, 32966.34067318664, 4204.957950420488, 4230.91538169238, 4242.580185458247, 4259.16946195492, 4260.637845783112, 308746.0, 311876.0, 311889.44055279804, 313159.0, 313209.0, 59024.40975590275, 61091.0, 68694.0, 69077.30922690709, 70496.64751676208, 65460.34539654638, 66369.0, 66713.6664316685, 66770.33229667641, 66804.66597667077, 17250.827491725173, 17386.21762020712, 17403.73894391585, 17415.825841741673, 17420.912895435442, 13007.739845203043, 13023.283719395524, 13041.217526948414, 13046.804297935538, 13084.934575327254, 22273.331800046024, 22422.0, 22424.439389015406, 22497.662535061987, 22580.758058306623, 8345.332373410234, 8517.403781735218, 8628.536316086665, 8637.996223990101, 8731.388802783744, 1025.702546261576, 1085.5820509103894, 1104.779044191165, 1168.193946177135, 1177.6349331707197, 30265.848670756506, 30833.38333233323, 31280.0, 31352.21619459486, 31631.36737265288, 1113.2596823112701, 1125.1561329003248, 1151.3609946479617, 1167.217963964146, 1167.3929556630505, 14680.632984175267, 15024.624384390478, 15738.449154279546, 17888.373906913195, 18064.81935180658, 425513.0, 429557.0, 431348.0, 431558.0, 432270.0, 60701.39298607046, 60880.391196087476, 61048.38951610427, 61180.38819611747, 61477.0, 1473.5800296915543, 1478.6746915678534, 1483.265783437184, 1500.1224283793886, 1511.05559025609, 32016.0, 32201.35597287994, 32224.516632251005, 32718.709690321448, 33749.156271093416, 46203.53796461992, 46731.29903051525, 50611.49388506068, 51682.74158629183, 52711.736441317546, 229359.0, 229633.0, 230155.0, 232158.0, 234624.0, 35392.11519712028, 35640.465393019644, 35649.0, 35914.28171436609, 35993.820030899675, 46606.53393466022, 47817.28274075961, 48334.27498587454, 48653.5134648649, 48676.5132348672, 38418.80790596029, 39055.804720976215, 39092.218155637296, 46287.30569041534, 46492.0, 40173.1965360697, 40442.39336410015, 40632.968351582895, 40742.59257407388, 40759.796201018806, 39015.6098439012, 39770.2045959085, 39889.0, 39961.2007759849, 39968.0, 34621.48067778935, 34715.30569388648, 35476.290474190886, 35819.28361432705, 36451.63548364482, 27997.099260907995, 28459.43081138407, 30200.84899575488, 30231.395372091996, 30843.537346940262], "unit": "ms", "name": "ext.html", "value": 20608.63196346526}, {"lowerIsBetter": false, "replicates": [381.9809009549522, 384.6634195079305, 385.2891415338699, 385.6953007124372, 388.9572147063823, 15188.924055379735, 16161.0, 17405.21676524556, 18921.526961825963, 18997.43007709769, 19198.136083876223, 19450.1247443865, 19742.0, 19947.60104797903, 20050.197992080335, 6026.126211699301, 6201.751929922798, 6227.159333489989, 6237.002079667254, 6237.625742455444, 4641.582257596816, 4701.694389864648, 5399.62202645815, 6254.155688981998, 7065.717371305167, 6537.93462065378, 6986.161660600735, 7044.718211271543, 7045.3306935841265, 7050.0, 6670.7665231716655, 6712.060311556386, 6732.764353247638, 6738.022986666906, 6758.966205168992, 6819.693113809891, 6835.487338449636, 6839.658017099175, 6841.897371539431, 6846.418054465376, 18067.54831129232, 18486.16812243439, 18489.907550462296, 18511.444656660322, 21773.2379366723, 2516.094206085808, 2707.2284398946204, 2752.6972033076345, 2752.9036483723175, 2764.8202866813594, 15509.0, 15517.767233491506, 15607.687846243125, 15850.841491585166, 16070.116143612096, 1910.1022519415853, 1969.1926310212743, 2057.1565658080267, 2112.091800525773, 2137.7541582717877, 60512.394876051556, 61359.38640613581, 61466.385336146515, 70754.64622676854, 78626.6068669653, 17041.48875533736, 17501.212445439985, 17514.211860466054, 17542.386016489618, 17546.0, 27420.0, 27609.723902761118, 27705.86147069252, 28005.299867503476, 28090.85954570214, 10467.267291289689, 10561.207909406825, 10577.735556610993, 10595.258331916692, 10605.628802991858, 8639.438436501732, 8648.221660050625, 8670.783230419163, 8674.86987695185, 8969.192772650493, 7248.637568121679, 8559.871601925975, 8561.0, 8611.569421528899, 8637.784055398666, 2725.4004119093765, 3137.262743255333, 3227.483602623569, 3298.884539041122, 3354.1279267390655, 429.10745649049903, 433.15534707320853, 468.55487287077415, 996.2577879479834, 1049.4700176410863, 10214.182865370753, 10499.842502362471, 10692.0, 11203.383813890314, 11215.66353009411, 469.8637395155367, 477.06257204592447, 477.44377799863463, 480.4378876714183, 481.9397575303087, 6023.728932198062, 6121.449069583669, 6150.477209437248, 6163.3528479509905, 6221.626702397871, 633385.0, 655334.0, 659728.0, 667988.0, 674699.0, 84291.15708842955, 86466.1353386458, 86508.0, 87786.56106719426, 88389.55805220932, 494.58207814396775, 498.74813219324733, 499.54042281101476, 500.36453703795974, 501.0680134948992, 10743.140548755924, 10778.568857245937, 10789.651293588302, 10947.890521095005, 10990.340579565252, 37384.0, 37909.241815164096, 38003.42994855135, 38726.6127338723, 38852.0, 49905.0, 51963.740181298854, 53962.73018635039, 54948.45051549433, 55563.44436555583, 9683.080107389787, 9961.40231586107, 10057.346272492265, 10197.643082492077, 10211.795764084825, 10684.679459616224, 13679.580104973757, 18582.0, 18588.721169182743, 19193.232270709017, 15569.221538922782, 15649.530514084596, 15689.607759806097, 15732.977356471793, 15767.290471928562, 15049.990530209036, 15232.086074835544, 15414.152221627917, 15492.457763978207, 15835.762463563286, 15896.364145434516, 15916.0450372978, 16163.030218186947, 16411.261493232596, 16473.752893706845, 10033.849492257768, 10226.948865255627, 10393.272470927264, 10395.740106497398, 10412.947935260276, 10933.617323393642, 14937.402503899722, 15163.241837907839, 15386.69226615439, 15518.612034699223], "unit": "ms", "name": "jquery.html", "value": 9605.607595426236}, {"lowerIsBetter": false, "replicates": [1557.1902610642467, 1564.1631727026036, 1616.062683643487, 1791.265581111744, 1833.8349548540632, 7757.534547927128, 7951.840963180733, 7961.4825036372595, 7964.721234756785, 7968.1235064142975, 6683.866322673543, 6882.380585747282, 7486.139094004177, 7712.0, 7788.883166752503, 2292.644640080786, 2306.630939049752, 2319.5013072189477, 2326.5695846268495, 2328.988355058222, 2005.1878989009401, 2299.183789754638, 2328.5692146952783, 2344.847584906984, 2348.729896061949, 1953.1308567687386, 2227.9331620051425, 2295.735990361105, 2300.769923007694, 2301.4016355747544, 2256.4697296135396, 2280.612295909691, 2287.5424915017065, 2333.265021518216, 2333.9649905251435, 2294.4722713775923, 2307.4462129088897, 2326.6044772388564, 2334.9533009339716, 2337.0418128567208, 7743.457957942946, 7760.611969401507, 7791.1040230373665, 7828.178041305695, 7828.452008359417, 4559.17934771741, 4669.719816810968, 4671.0, 4674.088552732219, 4679.625629949596, 2918.1537354167476, 3001.86491607876, 3012.0963710886645, 3039.6200474940633, 3043.6651968283472, 987.9753006174831, 999.6501224571415, 1052.100454111731, 1082.2965072702727, 1104.8729396119468, 10426.5829366826, 10427.217958653055, 10460.581576736922, 10484.947575262075, 10491.318064325795, 4001.379786133154, 4061.614146656063, 4064.5122585289364, 4072.592740725963, 4115.053537686349, 9018.413803102778, 9032.36773425854, 9036.141566551167, 9052.637894484147, 9058.682946096855, 4515.164694531522, 4569.977150114228, 4575.405197324393, 4586.701864378805, 4596.908061838744, 4129.587041295847, 4151.958480415217, 4163.625273725379, 4165.062860856283, 4182.832686692498, 3801.4107813288983, 3866.8066596670615, 3872.2642697887873, 3886.028492876781, 3898.941515877264, 1098.741795678015, 1170.303669316755, 1231.4335405713473, 1244.153975296807, 1266.341502418737, 1033.6330602636106, 1170.2276497511602, 1204.8915597596258, 1367.453018792471, 1473.1971075763734, 7390.704371825174, 7403.666834992438, 7440.925590744023, 7538.283863033003, 7539.0, 1203.6569577670323, 1209.3167360441498, 1214.8967337776212, 1220.0605533738972, 1235.5552001279532, 5399.487048730364, 5416.864578385572, 5429.837104886859, 5431.049566325956, 5456.972715136479, 9016.053314402025, 9029.322800789967, 9200.861987070199, 9323.067693230623, 9328.813423731488, 9100.954495227481, 9203.40177888435, 9207.585658645377, 9209.907900920905, 9224.0, 6938.444924406035, 7066.470014748915, 7287.781366559012, 7303.45224108194, 7309.086364204474, 7306.4885458017425, 7405.851882962418, 7406.592637404994, 7427.22014188524, 7428.70285188608, 8366.330693544624, 8389.538575378412, 8419.747407577783, 8446.408751387344, 8574.31405487547, 8534.103919088655, 8584.098669639849, 8730.039695633603, 8730.852844898622, 8733.08302628215, 7027.086478757728, 7094.361507464353, 7119.928800711927, 7138.714451422093, 7172.318629730167, 7617.771466856003, 7656.119546252252, 7674.424418168548, 7676.347510461674, 7694.230576942261, 6310.6213627182515, 6969.756058537927, 7066.787996360117, 7104.857902841811, 7135.82160445993, 6454.548181627241, 6511.511636627177, 7003.649817509002, 7008.544444611084, 7200.207977122408, 7201.279872012757, 7205.459590530626, 7243.23945985685, 7250.492465527363, 7292.96353518229, 6647.900281495685, 6681.432078273401, 6683.0, 6692.0, 6703.430208432338, 6359.268684101203, 6366.618002919654, 6376.808695739135, 6385.584936979081, 6386.457151142205], "unit": "ms", "name": "mootools.html", "value": 4515.740411716305}, {"lowerIsBetter": false, "replicates": [332.43983887150137, 343.9329330780496, 344.4953143644561, 348.1487762420881, 351.1046830582014, 6032.638041717494, 6112.480439162671, 6129.969350153254, 6135.509159267259, 6152.876942461148, 6042.184305118808, 6047.274327080745, 6047.818565443033, 6066.787662431805, 6076.665783381913, 4493.5506449355125, 4697.765111744399, 4700.365450664168, 4702.412198475191, 4705.129551033053, 4638.605718513913, 4640.234361330394, 4647.000894807616, 4656.697314674535, 4661.720296782187, 4615.353850460938, 4616.215243408612, 4616.746078965655, 4621.537846215385, 4626.537346265363, 4314.223439780838, 4421.889452763675, 4494.415725955605, 4602.102589994953, 4636.675432719711, 3995.360742281221, 4000.559938406802, 4003.73975691582, 4007.3788562772816, 4016.4376987221813, 5567.972160139214, 5730.4269573042775, 5743.310802703702, 5758.62568933018, 5762.567807414419, 5117.923231151536, 5254.106801843657, 5341.946580534222, 5344.91982620261, 5345.732713364355, 6348.714307856158, 6363.649999250039, 6374.904376434357, 6375.585586936881, 6377.075324077982, 4419.867403977886, 4423.955760442386, 4426.933595996063, 4427.048184640318, 4432.889177770549, 6523.673816309166, 6592.439642630382, 6652.634105124216, 6653.500987425914, 6677.031830384615, 4561.270196768567, 4563.133004729091, 4565.132624801277, 4567.543245675406, 4567.725936443824, 4607.470140933836, 4622.352870598119, 4623.653226007996, 4625.051864367797, 4626.676132670748, 5334.706591137525, 5336.252924590561, 5345.1447768356875, 5346.572274218053, 5352.65207761502, 6305.873882522324, 6411.711472983727, 6464.967675161594, 6467.353264673589, 6479.157709497735, 1572.3003263547612, 1631.8123415807097, 1632.6571420001933, 1634.8038235411827, 1635.492997170881, 1058.718950070408, 1086.896744809242, 1087.3475914451267, 1101.5758932810925, 1112.866456025282, 6308.589941653778, 6312.400321969406, 6315.621062736159, 6319.146915166486, 6325.019621958604, 6674.799756007327, 6674.933250667528, 6677.899831502531, 6678.432333251728, 6678.4991125665765, 6181.660008699475, 6318.399752023551, 6322.0, 6328.651924144124, 6329.841753956188, 3581.0, 3603.279344131184, 3607.819609019591, 3610.0072480068193, 3610.332088563624, 6858.794236172923, 6860.142482189726, 6864.279250678706, 6871.4502839773695, 6874.0, 6544.083828264047, 6677.131972843499, 6728.360805723449, 6802.523823332418, 6831.11195544576, 6918.654067296615, 6926.792196234121, 6951.826204344932, 6957.234704182637, 6965.721371145098, 6633.6019838809825, 6664.733410663519, 6668.966655166693, 6675.0, 6714.462842972648, 6459.515536334699, 6634.568753031038, 6641.734330626721, 6644.501662375438, 6652.900206497004, 6898.586084834926, 6933.0, 6936.861262774615, 6940.41006514452, 6942.1669399670345, 4630.652701047367, 4630.699004564693, 4631.768411579475, 4633.606143477842, 4634.83778067766, 6485.0596663484475, 6695.564788288746, 6705.597664140168, 6719.697613607497, 6730.46156307504, 6562.212534495891, 6564.343565643398, 6568.671566421755, 6573.80278591643, 6576.8355791105605, 6513.869722605426, 6526.804195874131, 6528.934710653023, 6529.314421985813, 6530.8367290818105, 6198.876022479435, 6351.587146835441, 6360.936390636034, 6440.710168042543, 6449.193850768654, 6565.737370505126, 6568.080468734285, 6568.1789776277965, 6577.6053436793945, 6578.276389597045, 6408.839779005562, 6409.3270206629495, 6416.230052393743, 6416.807495775134, 6427.55007149495], "unit": "ms", "name": "prototype.html", "value": 4866.744078658461}, {"lowerIsBetter": false, "replicates": [906.614688757278, 912.3339961827866, 955.2978560757842, 955.8661787349772, 957.6696039866246, 14367.066140700861, 14789.482368117091, 14805.629859253511, 14814.111153330781, 14832.258387080657, 13858.930705346484, 13873.791893121586, 13907.930460347709, 13927.93036034821, 13967.0, 915.8763566918481, 932.9766755831091, 933.9299552533553, 934.3039435620473, 934.9018353072931, 931.0875342164683, 934.313279739391, 937.4141161773891, 938.0713094036888, 938.2962777916563, 968.9224862011022, 970.3886551472563, 975.3757595139081, 975.7560609847538, 975.868257785197, 966.0580933589764, 966.9854952175688, 966.9854952175723, 968.2737946540095, 973.464594473044, 712.6757325416922, 713.8536599996988, 716.3373879161754, 716.7706333973131, 716.9462290328246, 15021.0, 15132.848671513255, 15143.167125808077, 15175.848241517553, 15180.240987950558, 11378.487968041458, 12121.27272363661, 12137.332446715427, 12692.365381730877, 12799.74400511994, 10805.945970270177, 10999.175061870392, 11073.390963497004, 11077.944610276978, 11088.889111108867, 3706.3143318485895, 3993.900152496211, 4003.2994226010333, 4022.4971878515184, 4049.7570145791346, 43626.563734362564, 44601.33098003532, 46118.07763844738, 48296.51703482955, 52075.739621301655, 9986.300958932805, 10187.847182292124, 10278.743031424274, 10703.571857125628, 10790.56837726482, 19724.0, 19841.107150178275, 19871.701924471145, 19877.403677889688, 19878.801211987695, 7929.762107136795, 8021.3181879539725, 8074.434789564792, 8303.211194936472, 8319.126491718403, 4704.341392205094, 4724.480307166209, 4792.0, 4854.0, 4923.950760492349, 3264.8041117533025, 3275.11571875597, 3282.081017315108, 3287.589051368579, 3302.8183449910007, 559.4768891086846, 560.8205374280191, 566.832784328624, 567.7473524281655, 587.4536680886816, 659.3110199841177, 669.6149713914518, 669.8928171492538, 675.6250281093942, 692.5567636712508, 8525.275351595184, 8895.4662720237, 9788.804223915482, 9797.510124493747, 9962.900370996342, 540.8695825724177, 684.4079870911626, 694.5797792335666, 702.3047183288508, 720.945929055323, 4214.759622324081, 4271.145770845843, 4336.479622445327, 4389.122175564899, 4420.491643460979, 57969.71015144981, 59681.0, 60271.69864150739, 60471.0, 60574.0, 49038.75480622574, 53310.200346994825, 54217.72891135519, 54286.72856635691, 54591.45408545943, 48991.0, 49207.0, 49415.50584494181, 49484.257736133986, 50271.74864125729, 9572.76068098303, 9610.711678649652, 9688.75778105553, 9765.0, 10031.548580313758, 38718.41922371223, 39683.60316396799, 40782.79608601938, 41082.0, 41360.0, 47143.292850607955, 47341.76329118332, 47709.28436073531, 47828.04343913033, 48025.27962080642, 11323.660290191307, 11605.535778568763, 11687.532498699957, 11713.238639488405, 12103.0, 12849.614511564669, 12932.353382330657, 13020.869791302344, 13183.0, 13222.735545288848, 11399.943000284946, 11515.539378425105, 11544.01875840563, 11718.94140529326, 11728.0, 11337.149713771665, 11394.544218231178, 11417.37204453763, 11541.480633371351, 11738.237014594024, 11694.824577631172, 11856.34790086519, 11967.581134660593, 12230.327331996825, 12354.0, 9622.230221582116, 9771.413715177112, 9847.261455391017, 9942.15491683215, 10088.142507886912, 9656.275779316438, 10069.496525173858, 10189.74525636865, 10233.744156395851, 10565.26043177001], "unit": "ms", "name": "yui.html", "value": 6780.831800745604}], "name": "dromaeo_css", "value": 7395.656752617207}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 110.0, 111.0, 110.0, 99.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.3}, {"lowerIsBetter": true, "replicates": [148.0, 121.0, 149.0, 166.0, 124.0, 152.0, 153.0, 123.0, 168.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 160.0, 159.0, 160.0, 161.0, 163.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 73.0, 148.0, 73.0, 71.0, 70.0, 71.0, 151.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.0}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 132.0, 93.0, 93.0, 93.0, 138.0, 93.0, 94.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 110.0, 110.0, 109.0, 109.0, 111.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 64.0, 59.0, 57.0, 58.0, 58.0, 56.0, 53.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.3}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 63.0, 63.0, 65.0, 64.0, 64.0, 64.0, 65.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.9}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 77.0, 77.0, 78.0, 81.0, 80.0, 80.0, 79.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.4}, {"lowerIsBetter": true, "replicates": [118.0, 111.0, 117.0, 110.0, 121.0, 121.0, 115.0, 119.0, 113.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.7}, {"lowerIsBetter": true, "replicates": [129.0, 143.0, 140.0, 142.0, 142.0, 134.0, 151.0, 138.0, 141.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.1}, {"lowerIsBetter": true, "replicates": [64.0, 62.0, 67.0, 62.0, 66.0, 64.0, 64.0, 65.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.8}], "name": "kraken", "value": 1473.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.49717029669011], "unit": "score", "name": "Richards", "value": 205.84596199999996}, {"lowerIsBetter": false, "replicates": [168.9189189189189], "unit": "score", "name": "DeltaBlue", "value": 391.41855999999996}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [943.3962264150944], "unit": "score", "name": "RayTrace", "value": 784.3883400000002}, {"lowerIsBetter": false, "replicates": [544.9591280653951], "unit": "score", "name": "Earley", "value": 1222.959605}, {"lowerIsBetter": false, "replicates": [10568.421052631578], "unit": "score", "name": "Boyer", "value": 63.06173804780874}, {"lowerIsBetter": false, "replicates": [26500.0], "unit": "score", "name": "RegExp", "value": 34.37679245283018}, {"lowerIsBetter": false, "replicates": [514.668039114771], "unit": "score", "name": "Splay", "value": 158.33701299999998}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23032.925857998198}]} 07:07:29 INFO - RETURN: dromaeo_css: 7395.65 07:07:29 INFO - RETURN: kraken: 1473.80 07:07:29 INFO - RETURN: v8_7: 23032.93 07:07:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23032.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1473.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7395.65"}}} 07:07:29 INFO - Return code: 0 07:07:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 07:07:29 INFO - # TBPL SUCCESS # 07:07:29 INFO - Running post-action listener: _resource_record_post_action 07:07:29 INFO - Running post-run listener: _resource_record_post_run 07:07:30 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 315392; Write bytes: 511614976; Read time: 80; Write time: 626636 07:07:30 INFO - install - Wall time: 15s; CPU: 17.0%; Read bytes: 0; Write bytes: 249610240; Read time: 0; Write time: 442116 07:07:30 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 261820416; Read time: 80; Write time: 184480 07:07:30 INFO - Running post-run listener: _upload_blobber_files 07:07:30 INFO - Blob upload gear active. 07:07:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:07:30 INFO - Copying logs to upload dir... 07:07:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.393111 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-10 07:07:30.596742) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:30.600833) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010202 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455107060/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-10 07:07:30.652914) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:30.653287) ========= 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=cbe409c6de289db6ac707de3000001d4-1455113150.759952-566507267 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-10 07:07:30.703623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-10 07:07:30.703974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-10 07:07:30.704295) ========= ========= Total master_lag: 0.26 =========