builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1455212554.48 results: success (0) buildid: 20160211065236 builduid: 715e83f389f24cf69b5a3e53582067f1 revision: 46bf92e380db ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.478714) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.479685) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.480346) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006232 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.506973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.507681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.522371) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.523013) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004519 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.576145) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.576857) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.577738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.578442) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-11 09:42: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-02-11 09:42:35 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172928 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.768785) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.769559) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016175 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.823428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:34.824250) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 46bf92e380db --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 46bf92e380db --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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-11 09:42:35,408 Setting DEBUG logging. 2016-02-11 09:42:35,408 attempt 1/10 2016-02-11 09:42:35,408 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/46bf92e380db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-11 09:42:35,862 unpacking tar archive at: mozilla-beta-46bf92e380db/testing/mozharness/ program finished with exit code 0 elapsedTime=0.719913 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:35.563389) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:35.564267) ========= echo 46bf92e380db in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'46bf92e380db'] 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 46bf92e380db program finished with exit code 0 elapsedTime=0.004613 script_repo_revision: '46bf92e380db' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:35.620721) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:35.621050) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 09:42:35.631805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 44 secs) (at 2016-02-11 09:42:35.632502) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:42:36 INFO - MultiFileLogger online at 20160211 09:42:36 in /builds/slave/test-pgo 09:42: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 09:42:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:42:36 INFO - {'append_to_log': False, 09:42:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:42:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:42:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:42:36 INFO - 'branch': 'Mozilla-Beta', 09:42:36 INFO - 'buildbot_json_path': 'buildprops.json', 09:42:36 INFO - 'config_files': ('talos/linux_config.py',), 09:42:36 INFO - 'default_actions': ('clobber', 09:42:36 INFO - 'read-buildbot-config', 09:42:36 INFO - 'download-and-extract', 09:42:36 INFO - 'populate-webroot', 09:42:36 INFO - 'create-virtualenv', 09:42:36 INFO - 'install', 09:42:36 INFO - 'run-tests'), 09:42:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:42:36 INFO - 'download_minidump_stackwalk': True, 09:42:36 INFO - 'download_symbols': 'ondemand', 09:42:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:42:36 INFO - 'tooltool.py': '/tools/tooltool.py', 09:42:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:42:36 INFO - '/tools/misc-python/virtualenv.py')}, 09:42:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:42:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:42:36 INFO - 'installer_path': 'installer.exe', 09:42:36 INFO - 'log_level': 'info', 09:42:36 INFO - 'log_name': 'talos', 09:42:36 INFO - 'log_to_console': True, 09:42:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:42:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:42:36 INFO - 'opt_config_files': (), 09:42:36 INFO - 'pip_index': False, 09:42:36 INFO - 'sps_profile': False, 09:42:36 INFO - 'sps_profile_interval': 0, 09:42:36 INFO - 'suite': 'dromaeojs', 09:42:36 INFO - 'system_bits': '32', 09:42:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:42:36 INFO - 'title': 'talos-linux64-ix-057', 09:42:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:42:36 INFO - 'use_talos_json': True, 09:42:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:42:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:42:36 INFO - 'work_dir': 'build'} 09:42:36 INFO - ##### 09:42:36 INFO - ##### Running clobber step. 09:42:36 INFO - ##### 09:42:36 INFO - Running pre-action listener: _resource_record_pre_action 09:42:36 INFO - Running main action method: clobber 09:42:36 INFO - rmtree: /builds/slave/test-pgo/build 09:42:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:42:36 INFO - Running post-action listener: _resource_record_post_action 09:42:36 INFO - ##### 09:42:36 INFO - ##### Running read-buildbot-config step. 09:42:36 INFO - ##### 09:42:36 INFO - Running pre-action listener: _resource_record_pre_action 09:42:36 INFO - Running main action method: read_buildbot_config 09:42:36 INFO - Using buildbot properties: 09:42:36 INFO - { 09:42:36 INFO - "properties": { 09:42:36 INFO - "buildnumber": 117, 09:42:36 INFO - "product": "firefox", 09:42:36 INFO - "script_repo_revision": "production", 09:42:36 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:42:36 INFO - "repository": "", 09:42:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:42:36 INFO - "buildid": "20160211065236", 09:42:36 INFO - "slavename": "talos-linux64-ix-057", 09:42:36 INFO - "pgo_build": "True", 09:42:36 INFO - "basedir": "/builds/slave/test-pgo", 09:42:36 INFO - "project": "", 09:42:36 INFO - "platform": "ubuntu64_hw", 09:42:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:42:36 INFO - "slavebuilddir": "test", 09:42:36 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:42:36 INFO - "branch": "mozilla-beta", 09:42:36 INFO - "repo_path": "releases/mozilla-beta", 09:42:36 INFO - "stage_platform": "linux64-pgo", 09:42:36 INFO - "builduid": "715e83f389f24cf69b5a3e53582067f1", 09:42:36 INFO - "revision": "46bf92e380db" 09:42:36 INFO - }, 09:42:36 INFO - "sourcestamp": { 09:42:36 INFO - "repository": "", 09:42:36 INFO - "hasPatch": false, 09:42:36 INFO - "project": "", 09:42:36 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:42:36 INFO - "changes": [ 09:42:36 INFO - { 09:42:36 INFO - "category": null, 09:42:36 INFO - "files": [ 09:42:36 INFO - { 09:42:36 INFO - "url": null, 09:42:36 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:42:36 INFO - } 09:42:36 INFO - ], 09:42:36 INFO - "repository": "", 09:42:36 INFO - "rev": "46bf92e380db", 09:42:36 INFO - "who": "sendchange", 09:42:36 INFO - "when": 1455212401, 09:42:36 INFO - "number": 7594756, 09:42:36 INFO - "comments": "Bug 1243549 - Make sure that startup sanitization doesn_t throw because it can_t find a tabbrowser. r=mak, a=sylvestre", 09:42:36 INFO - "project": "", 09:42:36 INFO - "at": "Thu 11 Feb 2016 09:40:01", 09:42:36 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:42:36 INFO - "revlink": "", 09:42:36 INFO - "properties": [ 09:42:36 INFO - [ 09:42:36 INFO - "buildid", 09:42:36 INFO - "20160211065236", 09:42:36 INFO - "Change" 09:42:36 INFO - ], 09:42:36 INFO - [ 09:42:36 INFO - "builduid", 09:42:36 INFO - "715e83f389f24cf69b5a3e53582067f1", 09:42:36 INFO - "Change" 09:42:36 INFO - ], 09:42:36 INFO - [ 09:42:36 INFO - "pgo_build", 09:42:36 INFO - "True", 09:42:36 INFO - "Change" 09:42:36 INFO - ] 09:42:36 INFO - ], 09:42:36 INFO - "revision": "46bf92e380db" 09:42:36 INFO - } 09:42:36 INFO - ], 09:42:36 INFO - "revision": "46bf92e380db" 09:42:36 INFO - } 09:42:36 INFO - } 09:42:36 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:42:36 INFO - Running post-action listener: _resource_record_post_action 09:42:36 INFO - ##### 09:42:36 INFO - ##### Running download-and-extract step. 09:42:36 INFO - ##### 09:42:36 INFO - Running pre-action listener: _resource_record_pre_action 09:42:36 INFO - Running main action method: download_and_extract 09:42:36 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/test_packages.json 09:42:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:42:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:42:36 INFO - Downloaded 1270 bytes. 09:42:36 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:42:36 INFO - Using the following test package requirements: 09:42:36 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:42:36 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:42:36 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'jsshell-linux-x86_64.zip'], 09:42:36 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:42:36 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:42:36 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:42:36 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:42:36 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:42:36 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:42:36 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:42:36 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:42:36 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:42:37 INFO - Downloaded 21581716 bytes. 09:42: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 09:42:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42: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 09:42:38 INFO - Return code: 0 09:42: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 09:42: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')]} 09:42:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:42:38 INFO - Downloaded 21581716 bytes. 09:42: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 09:42:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:42: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 09:42:40 INFO - Return code: 0 09:42: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')]} 09:42:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:42:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:42:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:42:40 INFO - Downloaded 11132038 bytes. 09:42: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 09:42:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:42: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 09:42:40 INFO - Return code: 0 09:42: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')]} 09:42:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:42:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:42:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:42:41 INFO - Downloaded 57938012 bytes. 09:42:41 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:42:41 INFO - mkdir: /builds/slave/test-pgo/properties 09:42:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:42:41 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:42:41 INFO - Contents: 09:42:41 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:42:41 INFO - Running post-action listener: _resource_record_post_action 09:42:41 INFO - Running post-action listener: set_extra_try_arguments 09:42:41 INFO - ##### 09:42:41 INFO - ##### Running populate-webroot step. 09:42:41 INFO - ##### 09:42:41 INFO - Running pre-action listener: _resource_record_pre_action 09:42:41 INFO - Running main action method: populate_webroot 09:42:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:42:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:42:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:42:41 INFO - '--tppagecycles', 09:42:41 INFO - '7'], 09:42:41 INFO - 'tests': ['tsvgm']}}, 09:42:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:42:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:42:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:42:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:42:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'tests': ['damp', 'tps']}, 09:42:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['damp', 'tps']}, 09:42:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:42:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:42:41 INFO - 'other': {'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'other_l64': {'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'other_nol64': {'tests': ['a11yr', 09:42:41 INFO - 'ts_paint', 09:42:41 INFO - 'tpaint', 09:42:41 INFO - 'sessionrestore', 09:42:41 INFO - 'sessionrestore_no_auto_restore']}, 09:42:41 INFO - 'svgr': {'tests': ['tsvgx', 09:42:41 INFO - 'tsvgr_opacity', 09:42:41 INFO - 'tart', 09:42:41 INFO - 'tscrollx', 09:42:41 INFO - 'cart']}, 09:42:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['tsvgx', 09:42:41 INFO - 'tsvgr_opacity', 09:42:41 INFO - 'tart', 09:42:41 INFO - 'tscrollx', 09:42:41 INFO - 'cart']}, 09:42:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'tests': ['tp5o']}, 09:42:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'talos_options': ['--e10s'], 09:42:41 INFO - 'tests': ['tp5o']}, 09:42:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'talos_options': ['--xperf_path', 09:42:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:41 INFO - 'tests': ['tp5n']}, 09:42:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:41 INFO - 'talos_options': ['--e10s', 09:42:41 INFO - '--xperf_path', 09:42:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:41 INFO - 'tests': ['tp5n']}}, 09:42:41 INFO - 'talos.zip': {'path': '', 09:42:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:42:41 INFO - Running post-action listener: _resource_record_post_action 09:42:41 INFO - ##### 09:42:41 INFO - ##### Running create-virtualenv step. 09:42:41 INFO - ##### 09:42:41 INFO - Running pre-action listener: _resource_record_pre_action 09:42:41 INFO - Running main action method: create_virtualenv 09:42:41 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:42:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:42:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:42:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:42:41 INFO - Using real prefix '/usr' 09:42:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:42:42 INFO - Installing distribute.............................................................................................................................................................................................done. 09:42:43 INFO - Installing pip.................done. 09:42:43 INFO - Return code: 0 09:42:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:43 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:42:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:42:43 INFO - Using env: {'DISPLAY': ':0', 09:42:43 INFO - 'HOME': '/home/cltbld', 09:42:43 INFO - 'LANG': 'en_US.UTF-8', 09:42:43 INFO - 'LANGUAGE': 'en_US:en', 09:42:43 INFO - 'LOGNAME': 'cltbld', 09:42:43 INFO - 'MAIL': '/var/mail/cltbld', 09:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:43 INFO - 'MOZ_NO_REMOTE': '1', 09:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:43 INFO - 'NO_EM_RESTART': '1', 09:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:43 INFO - 'SHELL': '/bin/bash', 09:42:43 INFO - 'SHLVL': '1', 09:42:43 INFO - 'TERM': 'linux', 09:42:43 INFO - 'TMOUT': '86400', 09:42:43 INFO - 'USER': 'cltbld', 09:42:43 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:43 INFO - '_': '/tools/buildbot/bin/python'} 09:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:42:44 INFO - Cleaning up... 09:42:44 INFO - Return code: 0 09:42:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:44 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:42:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:42:44 INFO - Using env: {'DISPLAY': ':0', 09:42:44 INFO - 'HOME': '/home/cltbld', 09:42:44 INFO - 'LANG': 'en_US.UTF-8', 09:42:44 INFO - 'LANGUAGE': 'en_US:en', 09:42:44 INFO - 'LOGNAME': 'cltbld', 09:42:44 INFO - 'MAIL': '/var/mail/cltbld', 09:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:44 INFO - 'MOZ_NO_REMOTE': '1', 09:42:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:44 INFO - 'NO_EM_RESTART': '1', 09:42:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:44 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:44 INFO - 'SHELL': '/bin/bash', 09:42:44 INFO - 'SHLVL': '1', 09:42:44 INFO - 'TERM': 'linux', 09:42:44 INFO - 'TMOUT': '86400', 09:42:44 INFO - 'USER': 'cltbld', 09:42:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:44 INFO - '_': '/tools/buildbot/bin/python'} 09:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:44 INFO - Downloading/unpacking psutil>=0.7.1 09:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:46 INFO - Installing collected packages: psutil 09:42:46 INFO - Running setup.py install for psutil 09:42:46 INFO - building 'psutil._psutil_linux' extension 09:42:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:42:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:42:46 INFO - building 'psutil._psutil_posix' extension 09:42:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:42:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:42:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:46 INFO - Successfully installed psutil 09:42:46 INFO - Cleaning up... 09:42:46 INFO - Return code: 0 09:42:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:46 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:42:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:42:46 INFO - Using env: {'DISPLAY': ':0', 09:42:46 INFO - 'HOME': '/home/cltbld', 09:42:46 INFO - 'LANG': 'en_US.UTF-8', 09:42:46 INFO - 'LANGUAGE': 'en_US:en', 09:42:46 INFO - 'LOGNAME': 'cltbld', 09:42:46 INFO - 'MAIL': '/var/mail/cltbld', 09:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:46 INFO - 'MOZ_NO_REMOTE': '1', 09:42:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:46 INFO - 'NO_EM_RESTART': '1', 09:42:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:46 INFO - 'SHELL': '/bin/bash', 09:42:46 INFO - 'SHLVL': '1', 09:42:46 INFO - 'TERM': 'linux', 09:42:46 INFO - 'TMOUT': '86400', 09:42:46 INFO - 'USER': 'cltbld', 09:42:46 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:46 INFO - '_': '/tools/buildbot/bin/python'} 09:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:42:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:42:48 INFO - Installing collected packages: mozsystemmonitor 09:42:48 INFO - Running setup.py install for mozsystemmonitor 09:42:49 INFO - Successfully installed mozsystemmonitor 09:42:49 INFO - Cleaning up... 09:42:49 INFO - Return code: 0 09:42:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:49 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:42:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:42:49 INFO - Using env: {'DISPLAY': ':0', 09:42:49 INFO - 'HOME': '/home/cltbld', 09:42:49 INFO - 'LANG': 'en_US.UTF-8', 09:42:49 INFO - 'LANGUAGE': 'en_US:en', 09:42:49 INFO - 'LOGNAME': 'cltbld', 09:42:49 INFO - 'MAIL': '/var/mail/cltbld', 09:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:49 INFO - 'MOZ_NO_REMOTE': '1', 09:42:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:49 INFO - 'NO_EM_RESTART': '1', 09:42:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:49 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:49 INFO - 'SHELL': '/bin/bash', 09:42:49 INFO - 'SHLVL': '1', 09:42:49 INFO - 'TERM': 'linux', 09:42:49 INFO - 'TMOUT': '86400', 09:42:49 INFO - 'USER': 'cltbld', 09:42:49 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:49 INFO - '_': '/tools/buildbot/bin/python'} 09:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:49 INFO - Downloading/unpacking blobuploader==1.2.4 09:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:42:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:42:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:42:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:51 INFO - Downloading docopt-0.6.1.tar.gz 09:42:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:42:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:42:51 INFO - Running setup.py install for blobuploader 09:42:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:42:51 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:42:51 INFO - Running setup.py install for requests 09:42:52 INFO - Running setup.py install for docopt 09:42:52 INFO - Successfully installed blobuploader requests docopt 09:42:52 INFO - Cleaning up... 09:42:52 INFO - Return code: 0 09:42:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:52 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:42:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:42:52 INFO - Using env: {'DISPLAY': ':0', 09:42:52 INFO - 'HOME': '/home/cltbld', 09:42:52 INFO - 'LANG': 'en_US.UTF-8', 09:42:52 INFO - 'LANGUAGE': 'en_US:en', 09:42:52 INFO - 'LOGNAME': 'cltbld', 09:42:52 INFO - 'MAIL': '/var/mail/cltbld', 09:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:52 INFO - 'MOZ_NO_REMOTE': '1', 09:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:52 INFO - 'NO_EM_RESTART': '1', 09:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:52 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:52 INFO - 'SHELL': '/bin/bash', 09:42:52 INFO - 'SHLVL': '1', 09:42:52 INFO - 'TERM': 'linux', 09:42:52 INFO - 'TMOUT': '86400', 09:42:52 INFO - 'USER': 'cltbld', 09:42:52 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:52 INFO - '_': '/tools/buildbot/bin/python'} 09:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:42:52 INFO - Running setup.py (path:/tmp/pip-invDoE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:42:52 INFO - Running setup.py (path:/tmp/pip-sgQyRq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:42:52 INFO - Running setup.py (path:/tmp/pip-pIisBv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:42:52 INFO - Running setup.py (path:/tmp/pip-z7GfVm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:42:52 INFO - Running setup.py (path:/tmp/pip-WzoJ2n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:42:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:42:52 INFO - Running setup.py (path:/tmp/pip-ZPUHE5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:42:53 INFO - Running setup.py (path:/tmp/pip-vtks_p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:42:53 INFO - Running setup.py (path:/tmp/pip-ifPOkr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:42:53 INFO - Running setup.py (path:/tmp/pip-RSXPH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:42:53 INFO - Running setup.py (path:/tmp/pip-1lyLkP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:42:53 INFO - Running setup.py (path:/tmp/pip-Z5PC5O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:42:53 INFO - Running setup.py (path:/tmp/pip-JMv1kh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:42:53 INFO - Running setup.py (path:/tmp/pip-1VkZVt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:42:53 INFO - Running setup.py (path:/tmp/pip-daPOac-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:42:53 INFO - Running setup.py (path:/tmp/pip-YqqYKq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:42:53 INFO - Running setup.py (path:/tmp/pip-Xp012F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:42:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:42:53 INFO - Running setup.py (path:/tmp/pip-UlMYsJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:42:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:42:53 INFO - Running setup.py install for manifestparser 09:42:53 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:42:53 INFO - Running setup.py install for mozcrash 09:42:54 INFO - Running setup.py install for mozdebug 09:42:54 INFO - Running setup.py install for mozdevice 09:42:54 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for mozfile 09:42:54 INFO - Running setup.py install for mozhttpd 09:42:54 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for mozinfo 09:42:54 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for mozInstall 09:42:54 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for mozleak 09:42:54 INFO - Running setup.py install for mozlog 09:42:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for moznetwork 09:42:54 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:42:54 INFO - Running setup.py install for mozprocess 09:42:55 INFO - Running setup.py install for mozprofile 09:42:55 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:42:55 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:42:55 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:42:55 INFO - Running setup.py install for mozrunner 09:42:55 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:42:55 INFO - Running setup.py install for mozscreenshot 09:42:55 INFO - Running setup.py install for moztest 09:42:55 INFO - Running setup.py install for mozversion 09:42:55 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:42:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:42:55 INFO - Cleaning up... 09:42:55 INFO - Return code: 0 09:42:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:55 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:42:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:42:55 INFO - Using env: {'DISPLAY': ':0', 09:42:55 INFO - 'HOME': '/home/cltbld', 09:42:55 INFO - 'LANG': 'en_US.UTF-8', 09:42:55 INFO - 'LANGUAGE': 'en_US:en', 09:42:55 INFO - 'LOGNAME': 'cltbld', 09:42:55 INFO - 'MAIL': '/var/mail/cltbld', 09:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:55 INFO - 'MOZ_NO_REMOTE': '1', 09:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:55 INFO - 'NO_EM_RESTART': '1', 09:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:55 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:55 INFO - 'SHELL': '/bin/bash', 09:42:55 INFO - 'SHLVL': '1', 09:42:55 INFO - 'TERM': 'linux', 09:42:55 INFO - 'TMOUT': '86400', 09:42:55 INFO - 'USER': 'cltbld', 09:42:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:55 INFO - '_': '/tools/buildbot/bin/python'} 09:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:42:55 INFO - Running setup.py (path:/tmp/pip-KwPXoh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:42:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:42:55 INFO - Running setup.py (path:/tmp/pip-7PRqGW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:42:55 INFO - Running setup.py (path:/tmp/pip-yulf9l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:42:56 INFO - Running setup.py (path:/tmp/pip-vQdGzi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:42:56 INFO - Running setup.py (path:/tmp/pip-yiFRDh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:42:56 INFO - Running setup.py (path:/tmp/pip-K0gNND-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:42:56 INFO - Running setup.py (path:/tmp/pip-hZ69qc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:42:56 INFO - Running setup.py (path:/tmp/pip-gGqPAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:42:56 INFO - Running setup.py (path:/tmp/pip-bwQLPe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:42:56 INFO - Running setup.py (path:/tmp/pip-7FmdN4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:42:56 INFO - Running setup.py (path:/tmp/pip-0DVLTi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:42:56 INFO - Running setup.py (path:/tmp/pip-U6z0EB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:42:56 INFO - Running setup.py (path:/tmp/pip-WrR58w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:42:56 INFO - Running setup.py (path:/tmp/pip-imfNrH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:42:56 INFO - Running setup.py (path:/tmp/pip-vNzVCS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:42:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:42:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:42:56 INFO - Running setup.py (path:/tmp/pip-bUdg7s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:42:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:42:57 INFO - Running setup.py (path:/tmp/pip-eQ5W3m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:42:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:58 INFO - Downloading blessings-1.6.tar.gz 09:42:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:42:58 INFO - Installing collected packages: blessings 09:42:58 INFO - Running setup.py install for blessings 09:42:59 INFO - Successfully installed blessings 09:42:59 INFO - Cleaning up... 09:42:59 INFO - Return code: 0 09:42:59 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:42:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:42:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:42:59 INFO - Reading from file tmpfile_stdout 09:42:59 INFO - Current package versions: 09:42:59 INFO - argparse == 1.2.1 09:42:59 INFO - blessings == 1.6 09:42:59 INFO - blobuploader == 1.2.4 09:42:59 INFO - docopt == 0.6.1 09:42:59 INFO - manifestparser == 1.1 09:42:59 INFO - mozInstall == 1.12 09:42:59 INFO - mozcrash == 0.16 09:42:59 INFO - mozdebug == 0.1 09:42:59 INFO - mozdevice == 0.47 09:42:59 INFO - mozfile == 1.2 09:42:59 INFO - mozhttpd == 0.7 09:42:59 INFO - mozinfo == 0.9 09:42:59 INFO - mozleak == 0.1 09:42:59 INFO - mozlog == 3.1 09:42:59 INFO - moznetwork == 0.27 09:42:59 INFO - mozprocess == 0.22 09:42:59 INFO - mozprofile == 0.27 09:42:59 INFO - mozrunner == 6.11 09:42:59 INFO - mozscreenshot == 0.1 09:42:59 INFO - mozsystemmonitor == 0.0 09:42:59 INFO - moztest == 0.7 09:42:59 INFO - mozversion == 1.4 09:42:59 INFO - psutil == 3.1.1 09:42:59 INFO - requests == 1.2.3 09:42:59 INFO - wsgiref == 0.1.2 09:42:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:59 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:42:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:42:59 INFO - Using env: {'DISPLAY': ':0', 09:42:59 INFO - 'HOME': '/home/cltbld', 09:42:59 INFO - 'LANG': 'en_US.UTF-8', 09:42:59 INFO - 'LANGUAGE': 'en_US:en', 09:42:59 INFO - 'LOGNAME': 'cltbld', 09:42:59 INFO - 'MAIL': '/var/mail/cltbld', 09:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:59 INFO - 'MOZ_NO_REMOTE': '1', 09:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:59 INFO - 'NO_EM_RESTART': '1', 09:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:59 INFO - 'SHELL': '/bin/bash', 09:42:59 INFO - 'SHLVL': '1', 09:42:59 INFO - 'TERM': 'linux', 09:42:59 INFO - 'TMOUT': '86400', 09:42:59 INFO - 'USER': 'cltbld', 09:42:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:59 INFO - '_': '/tools/buildbot/bin/python'} 09:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:42:59 INFO - Cleaning up... 09:42:59 INFO - Return code: 0 09:42:59 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:59 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:42:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:42:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:42:59 INFO - Using env: {'DISPLAY': ':0', 09:42:59 INFO - 'HOME': '/home/cltbld', 09:42:59 INFO - 'LANG': 'en_US.UTF-8', 09:42:59 INFO - 'LANGUAGE': 'en_US:en', 09:42:59 INFO - 'LOGNAME': 'cltbld', 09:42:59 INFO - 'MAIL': '/var/mail/cltbld', 09:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:59 INFO - 'MOZ_NO_REMOTE': '1', 09:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:42:59 INFO - 'NO_EM_RESTART': '1', 09:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:42:59 INFO - 'PWD': '/builds/slave/test-pgo', 09:42:59 INFO - 'SHELL': '/bin/bash', 09:42:59 INFO - 'SHLVL': '1', 09:42:59 INFO - 'TERM': 'linux', 09:42:59 INFO - 'TMOUT': '86400', 09:42:59 INFO - 'USER': 'cltbld', 09:42:59 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:59 INFO - '_': '/tools/buildbot/bin/python'} 09:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:59 INFO - Downloading/unpacking jsonschema 09:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:01 INFO - Downloading/unpacking functools32 (from jsonschema) 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:02 INFO - warning: no files found matching '*.txt' 09:43:02 INFO - no previously-included directories found matching 'build' 09:43:02 INFO - no previously-included directories found matching 'dist' 09:43:02 INFO - no previously-included directories found matching '.git*' 09:43:02 INFO - Installing collected packages: jsonschema, functools32 09:43:02 INFO - Running setup.py install for functools32 09:43:02 INFO - warning: no files found matching '*.txt' 09:43:02 INFO - no previously-included directories found matching 'build' 09:43:02 INFO - no previously-included directories found matching 'dist' 09:43:02 INFO - no previously-included directories found matching '.git*' 09:43:02 INFO - Successfully installed jsonschema functools32 09:43:02 INFO - Cleaning up... 09:43:02 INFO - Return code: 0 09:43:02 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:02 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 0x7f4e05ce0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17cb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1898600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, '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': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:43:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:43:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:43:02 INFO - Using env: {'DISPLAY': ':0', 09:43:02 INFO - 'HOME': '/home/cltbld', 09:43:02 INFO - 'LANG': 'en_US.UTF-8', 09:43:02 INFO - 'LANGUAGE': 'en_US:en', 09:43:02 INFO - 'LOGNAME': 'cltbld', 09:43:02 INFO - 'MAIL': '/var/mail/cltbld', 09:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:02 INFO - 'MOZ_NO_REMOTE': '1', 09:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:43:02 INFO - 'NO_EM_RESTART': '1', 09:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:43:02 INFO - 'PWD': '/builds/slave/test-pgo', 09:43:02 INFO - 'SHELL': '/bin/bash', 09:43:02 INFO - 'SHLVL': '1', 09:43:02 INFO - 'TERM': 'linux', 09:43:02 INFO - 'TMOUT': '86400', 09:43:02 INFO - 'USER': 'cltbld', 09:43:02 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:02 INFO - '_': '/tools/buildbot/bin/python'} 09:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:02 INFO - Downloading/unpacking flake8 09:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:43:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:43:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:43:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:43:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:43:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:43:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:43:04 INFO - no previously-included directories found matching 'docs/_build' 09:43:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:43:04 INFO - Running setup.py install for pep8 09:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:43:05 INFO - no previously-included directories found matching 'docs/_build' 09:43:05 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:43:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:43:05 INFO - Cleaning up... 09:43:05 INFO - Return code: 0 09:43:05 INFO - Running post-action listener: _resource_record_post_action 09:43:05 INFO - Running post-action listener: _start_resource_monitoring 09:43:05 INFO - Starting resource monitoring. 09:43:05 INFO - ##### 09:43:05 INFO - ##### Running install step. 09:43:05 INFO - ##### 09:43:05 INFO - Running pre-action listener: _resource_record_pre_action 09:43:05 INFO - Running main action method: install 09:43:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:43:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:43:05 INFO - Reading from file tmpfile_stdout 09:43:05 INFO - Detecting whether we're running mozinstall >=1.0... 09:43:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:43:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:43:05 INFO - Reading from file tmpfile_stdout 09:43:05 INFO - Output received: 09:43:05 INFO - Usage: mozinstall [options] installer 09:43:05 INFO - Options: 09:43:05 INFO - -h, --help show this help message and exit 09:43:05 INFO - -d DEST, --destination=DEST 09:43:05 INFO - Directory to install application into. [default: 09:43:05 INFO - "/builds/slave/test-pgo"] 09:43:05 INFO - --app=APP Application being installed. [default: firefox] 09:43:05 INFO - mkdir: /builds/slave/test-pgo/build/application 09:43:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:43:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:43:20 INFO - Reading from file tmpfile_stdout 09:43:20 INFO - Output received: 09:43:20 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:43:20 INFO - Running post-action listener: _resource_record_post_action 09:43:20 INFO - ##### 09:43:20 INFO - ##### Running run-tests step. 09:43:20 INFO - ##### 09:43:20 INFO - Running pre-action listener: _resource_record_pre_action 09:43:20 INFO - Running main action method: run_tests 09:43:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:43:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:43:20 INFO - Python 2.7.3 09:43:20 INFO - Return code: 0 09:43:20 INFO - grabbing minidump binary from tooltool 09:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:20 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 0x1927350>, '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 0x1928250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19286e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:43:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:43:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:43:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:43:20 INFO - Return code: 0 09:43:20 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:43:20 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:43:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:43:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:43:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:43:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:43:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:43:21 INFO - Return code: 0 09:43:21 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/1455202356/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:43:21 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/1455202356/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost 09:43:21 INFO - Using env: {'DISPLAY': ':0', 09:43:21 INFO - 'HOME': '/home/cltbld', 09:43:21 INFO - 'LANG': 'en_US.UTF-8', 09:43:21 INFO - 'LANGUAGE': 'en_US:en', 09:43:21 INFO - 'LOGNAME': 'cltbld', 09:43:21 INFO - 'MAIL': '/var/mail/cltbld', 09:43:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:43:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:21 INFO - 'MOZ_NO_REMOTE': '1', 09:43:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:43:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:43:21 INFO - 'NO_EM_RESTART': '1', 09:43:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:43:21 INFO - 'PWD': '/builds/slave/test-pgo', 09:43:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:43:21 INFO - 'SHELL': '/bin/bash', 09:43:21 INFO - 'SHLVL': '1', 09:43:21 INFO - 'TERM': 'linux', 09:43:21 INFO - 'TMOUT': '86400', 09:43:21 INFO - 'USER': 'cltbld', 09:43:21 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015', 09:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:21 INFO - '_': '/tools/buildbot/bin/python'} 09:43:21 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/1455202356/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost'] with output_timeout 3600 09:43:21 INFO - mozversion INFO | application_buildid: 20160211065236 09:43:21 INFO - mozversion INFO | application_changeset: 46bf92e380db45f5a44728ba023c41ab11e45b1e 09:43:21 INFO - mozversion INFO | application_display_name: Nightly 09:43:21 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:43:21 INFO - mozversion INFO | application_name: Firefox 09:43:21 INFO - mozversion INFO | application_remotingname: firefox 09:43:21 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:43:21 INFO - mozversion INFO | application_vendor: Mozilla 09:43:21 INFO - mozversion INFO | application_version: 45.0 09:43:21 INFO - mozversion INFO | platform_buildid: 20160211065236 09:43:21 INFO - mozversion INFO | platform_changeset: 46bf92e380db45f5a44728ba023c41ab11e45b1e 09:43:21 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:43:21 INFO - mozversion INFO | platform_version: 45.0 09:43:21 INFO - 2016-02-11 09:43:21,375 DEBUG : using testdate: 1455212601 09:43:21 INFO - 2016-02-11 09:43:21,375 DEBUG : actual date: 1455212601 09:43:21 INFO - 2016-02-11 09:43:21,389 INFO : starting webserver on 'localhost:39161' 09:43:21 INFO - 2016-02-11 09:43:21,390 INFO : Starting test suite talos-linux64-ix-057 09:43:21 INFO - 2016-02-11 09:43:21,390 INFO : Starting test dromaeo_css 09:43:21 INFO - 2016-02-11 09:43:21,390 DEBUG : operating with platform_type : linux_ 09:43:21 INFO - 2016-02-11 09:43:21,391 INFO : Initialising browser for dromaeo_css test... 09:43:21 INFO - 2016-02-11 09:43:21,408 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsdPyq7/profile http://localhost:39161/getInfo.html 09:43:25 INFO - 2016-02-11 09:43:25,252 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:43:25 INFO - 2016-02-11 09:43:25,252 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:43:25 INFO - 2016-02-11 09:43:25,252 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:43:25 INFO - 2016-02-11 09:43:25,252 DEBUG : BROWSER_OUTPUT: __metrics 09:43:25 INFO - 2016-02-11 09:43:25,397 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:43:26 INFO - 2016-02-11 09:43:26,209 INFO : Browser initialized. 09:43:26 INFO - 2016-02-11 09:43:26,210 INFO : Running cycle 1/1 for dromaeo_css test... 09:43:26 INFO - 2016-02-11 09:43:26,210 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsdPyq7/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:43:37 INFO - 2016-02-11 09:43:37,852 DEBUG : BROWSER_OUTPUT: RSS: Main: 132124672 09:43:37 INFO - 2016-02-11 09:43:37,853 DEBUG : BROWSER_OUTPUT: 09:44:28 INFO - 2016-02-11 09:44:28,596 DEBUG : BROWSER_OUTPUT: 1455212668593 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:46:36 INFO - 2016-02-11 09:46:36,712 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39161/tests/dromaeo/cssquery-ext.html) 09:46:37 INFO - 2016-02-11 09:46:37,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 206188544 09:46:37 INFO - 2016-02-11 09:46:37,870 DEBUG : BROWSER_OUTPUT: 09:49:39 INFO - 2016-02-11 09:49:39,863 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-ext.html (next: http://localhost:39161/tests/dromaeo/cssquery-jquery.html) 09:49:40 INFO - 2016-02-11 09:49:40,429 DEBUG : BROWSER_OUTPUT: RSS: Main: 218861568 09:49:40 INFO - 2016-02-11 09:49:40,429 DEBUG : BROWSER_OUTPUT: 09:52:42 INFO - 2016-02-11 09:52:42,208 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39161/tests/dromaeo/cssquery-mootools.html) 09:52:42 INFO - 2016-02-11 09:52:42,770 DEBUG : BROWSER_OUTPUT: RSS: Main: 198062080 09:52:42 INFO - 2016-02-11 09:52:42,770 DEBUG : BROWSER_OUTPUT: 09:55:44 INFO - 2016-02-11 09:55:44,532 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39161/tests/dromaeo/cssquery-prototype.html) 09:55:45 INFO - 2016-02-11 09:55:45,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 200306688 09:55:45 INFO - 2016-02-11 09:55:45,098 DEBUG : BROWSER_OUTPUT: 09:58:47 INFO - 2016-02-11 09:58:47,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39161/tests/dromaeo/cssquery-yui.html) 09:58:48 INFO - 2016-02-11 09:58:48,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 196493312 09:58:48 INFO - 2016-02-11 09:58:48,037 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/dromaeo/cssquery-yui.html (next: http://localhost:39161/tests/dromaeo/cssquery-dojo.html) 10:01:50 INFO - 2016-02-11 10:01:50,543 DEBUG : BROWSER_OUTPUT: RSS: Main: 239378432 10:01:50 INFO - 2016-02-11 10:01:50,543 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,543 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:01:50 INFO - 2016-02-11 10:01:50,544 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:01:50 INFO - 2016-02-11 10:01:50,544 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:01:50 INFO - 2016-02-11 10:01:50,544 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:01:50 INFO - 2016-02-11 10:01:50,544 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3284.573005509284;3320.2197483591335;3353.5472711183997;3360.2607426366208;3381.1547113221695;5470.444280853946;5474.315710536183;5500.1749737539385;5500.642458240217;5515.117581187011;5448.591355648323;5453.481919217668;5487.067198576242;5494.560435165187;5501.862453438666;4807.519248075199;5020.021095886304;5067.543921047105;5094.057599344133;5111.7699703513435;4889.6821706589135;4928.186849169882;4934.851954441355;4944;4949.059678661061;4842.685225460334;4899.657024008321;4933.358663373774;4939.43196532398;4940.2342636891335;4937.604991600663;4966.180580204262;5033.370828646419;5068.26510156029;5072.594192464594;5046.066477701637;5068.746562671889;5123.385193776733;5131.923021154686;5148.3564554430695;5036.571891389236;5135.075686376451;5217.087009773274;5335.813246536352;5368.221607866876;5883.941160588382;5897.292324920994;5904.704764761745;5938.732757025901;5939.970300148515;5932.406759324076;5960.552958528127;5961.225040744719;5966.642001479881;5970.164177015221;5200.557952574036;5307.3896501902;5448.237246785453;5522.475364840334;5568.470995255444;6003.339632640406;6056.939430605726;6062.9090563641585;6081.665508396992;6107.755689772448;5851.61964472308;5866.6186697864505;5870.178175055496;5878.559108066912;5891.116332550152;5688.061469857427;5764.481196692234;5797.130430435468;5900.291964964146;5950.226470558886;5869.530437564985;5895.498882595028;5895.911561326584;5910.822675319747;5913.733881975321;5862.970685146547;5897.174395584621;5904.232449781501;5908.763649454059;5918.082697181858;4399.340098985178;4422.513523512411;4470.642348612103;4535.523770004102;4536.387587675687;4086.877393678135;4091.243120022805;4115.814788334532;4116.403121547359;4139.834406623761;5715.142728590661;5739.713014349266;5743.655380677173;5747.109198074305;5779.335376431764;5599.776008959678;5694.202811606378;5722;5808.128780682847;5859.062549992066;5623.634463759842;5626.409227031103;5627.184058311522;5647.858803529863;5649.745761440745;4927.87680307988;5141.845744627667;5144.151215049475;5147.305113809588;5176.50823171798;5973.910391344133;5982.700864956735;6081.939180608226;6083.7870674526175;6121.84695382619;5883;5889.440503152194;5901.203337549376;5901.557383196363;5903.291605007426;5839.0949402842625;5878.588498805043;6128.295246046761;6134.202553667995;6138.785142519991;5921.3190483094995;6016.819495415145;6018.668973206516;6022.518198544193;6053.334133245427;5933.584649074523;5974.372690867396;5983.521318294439;5984.072468767348;5992.970035149797;5443.101888188405;5558.138488534284;5761.049426844524;5838.357780644041;5844.561657875592;5853.03424934898;5860.706964651836;5861.179434879035;5863.73613187399;5877.324107727666;5761.279840019997;5762.625429347079;5766.654000759968;5770.826875193751;5772.105323674669;5666.2350582670815;5759.798407055733;5770.2498675171955;5857.94142058591;5873.236479257669;5600.187972744011;5742.684152371659;5773.393793651606;5777.7977770777825;5778.855528611818 10:01:50 INFO - 2016-02-11 10:01:50,545 DEBUG : BROWSER_OUTPUT: |1;ext.html;572.0578635532368;604.1722839709596;610.5909040942569;614.7510258345369;619.3837132053608;29727.256818579546;41458.585414145884;41667.79166104165;41698.583014169846;41723.99999999996;46358.53641463584;47069;47226;47287.29069063958;47305.29042064363;7298.9635051824935;7433.851322973537;7434.888476672854;9031.3226508012;9181.816363672722;10688.412137332443;10902.945485272563;10945.781084378308;10958.12335013201;11007.339559626447;10682.732931676692;10733.248672592921;10813.729656758565;10820.783584328348;10826.837597436044;10729;10810.513526891309;10825.512851921643;10842.945785271062;10843.620473283476;10521.421321827296;10656.04095631397;10657.360558366445;10679.679609611723;10687.572497100107;42003;42451.78774106141;42588.870556472364;42687.7865610673;42709.35935960963;3339.5825521809775;3388.81361525116;3466.878659246914;3493.737969652286;3509.5613048368955;32474.188145296557;32754.83622581872;32849.17877053069;32948;32959.6704032959;3871.7096217783496;3932.823022963945;3997.400389941503;4020.035191554006;4105.651019663333;305075.47462262766;305354;305562;306263;306389;58945.115823262684;59420.70289648524;67612;68138.65930670315;72463;66390.33609663938;66420.33579664142;66685.66657166684;66775;67050;18145.909270453827;18274.90862545679;18298.45104646863;18309.3591724289;18401.53996150107;12932.288724120264;12990.545330913372;13033.869661303266;13040.152390094614;13087.672808179872;21816.345509634735;22010.559788804454;22057.779422205895;22157.556848863256;22162.778372216395;8329.916700833035;8473.110323416076;8524.104968978292;8647.870281945776;8708.651653933772;980.7401038724744;992.0823238504499;1132.1961407400672;1138.3568283919558;1144.250515912079;30012.399752004836;30141.56693951648;30859.228519287197;31590.526142107887;32901.67098328986;1084.3331351218944;1084.348146451729;1095.052779345876;1118.759466714644;1211.2284962155336;13788.655283617789;15012.324445399983;15125.773113403307;15376.615584610474;16493;359398;361707;362818;363280;363839;50296.24555631669;50363.496365035884;50641.49358506442;50738;50827;1363.9522616708368;1377.1392879450343;1379.1449301432974;1379.7585422551012;1387.4796951143321;28781.424371512032;28911;29076.7092329074;29078.418431630827;29485.85257073787;47301;47312.7634361826;47497.28754068761;47598.28602571033;47693;248437;271167;272720;299070;301414;47136.764316178196;47191;47377.76311118422;48188.5181148198;49804.25293620526;51153.488465114875;51224;51702.741486292325;51783.74108129435;52201.47798521966;41563;42749.572504275806;42865.57134428616;42975.785121074194;43654.34518482162;39468.80265598654;46892;47708;48130.51869481261;48718.5128148714;43254.567454326316;43708.12583748244;45910.31134532918;47327;47583.76208118937;36447.81776091103;38846.4173037399;38882;39137.80431097826;39567;28762.42475150527;28939;29196.27009324784;29441.411171776872;29609.703902961555 10:01:50 INFO - 2016-02-11 10:01:50,545 DEBUG : BROWSER_OUTPUT: |2;jquery.html;390.38709226514385;391.2292783217063;392.9057026313684;393.97833119178443;397.1223595853164;17578;19131.808681913175;19242.326518571852;19248;19835.305764298253;17044.147792610383;17084.743728844078;17097.40159094432;17211.13944302786;17957.7306340405;5604.719764011793;5736.1682556029355;5739.339975902762;5743.3108027036815;5745.138229265602;5617.578681598876;5681.460261275193;5686.971565142168;5709.172170035343;5731.627444216134;4141.254574176647;4249.383839343309;5629.268195134627;5640.605157638967;5640.746166422521;5981.371955944628;6109.63342199465;6164.7842325518395;6173.2900716417735;6174.320824709278;5189.143791274435;5346.438623944468;5362.276092727471;5419.729013549307;5459.3994660587305;12652.177608455513;13090.345482725921;16236.02583844973;19038.714419283722;20618.278360257467;2267.875266860322;2270.591293567157;2386.737458879522;2389.749076346976;2477.665515155467;9734.561944712505;9984.400935943866;10143.695689129338;10165.491725413774;10313.9484302578;1969.2418418908824;2079.6048750737464;2163.891805409739;2173.076442511926;2184.803367696915;66986;67406.66296668485;68152.97770533495;68705.65647171783;70367.29632703659;10952.890471095347;10998.450077496253;11011.724706882233;11023;11047.779044419227;14660.853391466162;14733.263336833115;14736.778948315783;14744.705105898036;14784.630384240265;7493.250674932463;7508.436867234979;7988.760337189894;8041.6381262843315;8044.798880027929;7684.884726729104;7976.441649084624;7982.640781164861;8012.799680008046;8028.879566806502;4756.453007904066;4784.68899521537;4799.808007679723;4803.303520989437;4836.975815120973;1913.2920819296949;1981.6234915366035;2000.2699014859472;2017.5057111007898;2020.0808632072371;416.41077874807416;431.4520558890255;432.8398492557774;913.7213149989184;1132.0773569540797;10580.206484513692;10870.866078908442;11381;11412.42937853104;11494.023008044243;460.617687319524;470.2358667165856;475.80016393114977;485.5702703107775;486.23660852462;5336.973315133399;6048.014543202608;6131.110988906584;6238.189035425366;6357.4914006879335;133443.33278333547;467766;469021;641498;643857;47757.283640745416;71076.28923710696;82758.58620706978;85774.57112714396;85805.57097214473;500.18469894073155;512.3262909274367;515.0317403281881;515.1628603519281;515.3352175693313;11046.116310694964;15786.526404207432;15825.287862046005;15871.68256634884;16660.50018499447;35999;36374.454383184806;36765.81617091897;37378;37998.810005949796;43126.78436607797;44808.551914480435;46796.532034679214;46860.53139468562;48856.51143488519;10351.120154786928;10391.168706503311;10667.839982400115;10681.946590266998;10715.946420267848;12364.505419783467;12450.875491245333;16811.495655130366;18867.90566047161;19638.410847674593;15528.058992625922;15634.374625014872;15896;16012.839871601602;16090.919545402197;14195.290235487977;14727.631809204855;14921.85078149248;15657.921710391374;15879.841201587837;15107.54677359681;15383;15392.69214615679;15724.528264152093;15724.76412853831;7877.684892604231;9494.430334179971;9989.900100998897;10010.099091081838;10026.949865250626;4890.706557606555;10719.678409647722;13499.920006399268;15614.687706245584;15903.363865445253 10:01:50 INFO - 2016-02-11 10:01:50,546 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1584.492962252079;1608.769291492008;1627.682601892631;1840.567466645338;1926.0369815092454;8106.148854370287;8325.666973321075;8326.084130745614;8328.25045745882;8333.833323333529;6878.793636190922;7157.928420715792;7453.925460745391;7556.433267504945;7749.845003099935;2324.2562380038353;2339.883005849709;2345.4605440748637;2355.6702061711376;2364.609839376501;2049.6515592349338;2373.9406514837183;2375.2992867104244;2436.512697460515;2438.878056097188;2026.0376321247363;2290.015293423827;2319;2327.8603283803027;2328.871912044837;2292.083166733303;2294.32317466347;2294.529621427612;2298.643710224918;2355.2463211772238;2338.1699496678602;2359.9528009439887;2390.7370189279163;2391.318474234835;2392.7487613800477;8198.918010819874;8293.709720159826;8297.336213102937;8303.875441868317;8305.626246818847;4820.517948205186;4911.287863259808;4946.752662366904;4951.579115775164;4963.70217786934;3162.794418362822;3205.6153261608524;3254.3491301739746;3275.197576593726;3298.0600528849404;1109.1293334732277;1116.7654792493586;1126.9965541831011;1152.2740673375804;1153.9769204615945;11300.660980170607;11340.6030788922;11367.886321136766;11405.828912566318;11422.657320280405;4284.828606855754;4292.892677683083;4312.439382880269;4343.543927887589;4364.956350436519;9767.267454940908;9768.07203315684;9771.41371517697;9774.5601447935;9777.951110244403;4808.759562021885;4821.276808478756;4842.346283251786;4852.587530059914;4855.198892182821;4375.146846364928;4401.1417773534495;4429.247028005247;4437.511873693891;4438.312061630452;3774.6031270143108;3814.713896457721;3845.057960799566;3849.711271654637;3852.3065848147044;1028.99485502572;1083.0469187115445;1138.004246284501;1151.6142092398973;1166.084623570493;1095.9287646303123;1146.2893006335958;1158.0214718562802;1173.2549830857297;1210.195220178589;7772.106207786064;7822.882656760153;7858.1356050834365;7880.448368614257;7912.010998625172;1213.6298428979069;1219.481720268882;1224.8530176378893;1229.3361584744316;1257.9622611321674;6368;6376.075469056961;6443.96778016107;6452.645104519296;6467.288598254188;9780;10001.949990250003;10024.649137280161;10042.497875106215;10138.898611013796;9670.323077384515;9931.50342482873;9942.204623630238;10009.54957026921;10028.097471227624;7767.650455729506;7784.688612455551;7816.921830781733;7831.569263690438;7907.406944479187;7396.14944281393;7423.220561841145;7439.739609113655;7456.067991501062;7519.210482899217;8783.648654054023;8921.286297096347;8957.462552246625;8966.699828524697;8980.955095224743;8933.330000250136;9108.22580080674;9184.816303674022;9218.815623687622;9353.766155846159;7052.823679408056;7138.750143744944;7143.785686429415;7153.320434558708;7155.284471553011;7897.05235371759;7928.405369597419;8041.67833286662;8049.798755031171;8061.314788243065;6607.57050791697;7239.457040721862;7310.52481588695;7373.410127189919;7494.17564067963;6955.617441040791;7507.962460187664;7525.962370188114;7568.243175682388;7606.885896711665;7466.328030477283;7489.588072656056;7497;7505.812354691177;7510.887336689845;6629.038789375417;7013.123359580052;7043.36609705129;7057.2237053925055;7063.82340441493;7003.859922801617;7005.614691192033;7085.14978202619;7104.076470058859;7110.537815042006 10:01:50 INFO - 2016-02-11 10:01:50,546 DEBUG : BROWSER_OUTPUT: |4;prototype.html;340.3074742898202;352.7795128044972;358.45335862809213;359.83447614097514;363.30971154805883;6060.515158787298;6071.514278857692;6089.573729838913;6113.755449782004;6114.847128821782;5752.942470575293;5870.589058765888;5945.256842894638;6064;6065.545084118698;4736.834210802623;4750.4299484061785;4750.667453278272;4750.809967601292;4753.096911586787;4522.751248681321;4757.476677565465;4761.714297142165;4778.331033655291;4784.5693887550115;4588;4588.839390621329;4714.222153344694;4726.196546587088;4728.574428301452;4677.625789936814;4694.577488026094;4698.342232087511;4699.835505757282;4705.858824235278;4162.583741625844;4168.979155104235;4170.833166673329;4174.123434078847;4177.206330797169;5670.092785154356;5761.423857614247;5766.596338256324;5769.480746732815;5782;5429.809956651498;5436.592255580807;5439.728013599344;5442.0476416627325;5445.9727701361635;6506.056621789861;6514.902276465857;6520.4457621102265;6530.542861999662;6532.216134063943;4514.142312960527;4514.6388288936805;4517.457905051415;4520.367148599199;4532.388127602764;6557.967210163919;6649.3350664933605;6695.2969938156275;6709.463242940553;6721.932780672229;4642.16441040616;4643.883902902455;4644.907101857944;4647.930281045787;4651.5813576778255;4697.060587882369;4697.553732395417;4702.835400760957;4705.294205869079;4709.552592503706;5426.62013659048;5448.427915068861;5448.83653490396;5449.5640348772795;5461.617686761968;6399.424051835264;6518.1200537927725;6550.377714117151;6555.344465553407;6556.0493728410065;1688.9746653800203;1704.181992643538;1705.8550023248163;1706.2918888661068;1706.4880535839447;1164.6214980131492;1167.4571324334056;1173.061550759389;1176.7528818948117;1185.5494911933583;6338.207724034496;6363.61818290904;6373.139626150503;6374.075759014917;6376.84057898547;6454.19322584677;6672.83317917056;6680.265170831205;6682.097916781172;6685.76599819004;6219.813405597839;6271.811845644638;6380.298167201604;6380.680965951684;6382.840428989312;3597.8021208833766;3626.7642603230706;3634.34581775283;3638.7452878298263;3638.7816730996224;6830.795076147624;7003.054587630707;7004.754833580801;7009.614471204132;7015.473839462061;6908.6545672716165;6920.031195632616;6920.446364290845;6925.099737034153;6928.688209030505;6999.370056694922;7000.8949865753075;7006.159260888624;7013.333733295329;7014;6583.967080164569;6683.264840867598;6683.8997415039785;6697.53117281805;6702.497312701665;6638.103855979574;6645.966770166118;6652.234992975869;6659.334066593302;6663.300353462817;6967.09427774386;6994.6852391643515;6994.790156295319;7007.719691212498;7009.649517524002;4533.206688829376;4758.881027974328;4764;4767.380240568704;4770.737609431515;6711.731530738911;6718.697658605277;6736.66316684154;6744.325567443217;6744.629045402354;6541.803745887631;6561;6563.343665633399;6563.606183628997;6568.80293591193;6405.263394709668;6534.8366290843105;6537.738490460329;6546.0508226307875;6550.541462097607;6431.356864313531;6441.5490915635455;6446.742130314735;6451.1290975717675;6456.225252969673;6560.40956313934;6566.343365663586;6568.540202185801;6569.211694596488;6572.934270657232;6307.653079080695;6409.327020662763;6409.8397540060005;6411.102445657519;6428.4857211423905 10:01:50 INFO - 2016-02-11 10:01:50,547 DEBUG : BROWSER_OUTPUT: |5;yui.html;881.7751473374288;884.2704768565927;884.7080463447063;897.129784109414;922.1055576091192;13882.305884705762;14307.356168972396;14359;14370.20963846988;14383.208923509203;13351;13375.866241337584;13398;13455.461781528751;13475;733.9449541284398;738.9445791565627;913.1234015345276;922.1792604581894;936.2556767369923;921.6497730862296;922.0594993107027;929.7350255177275;930.3255140023497;930.3394589841221;974.6539978307705;979.6571200079986;980.5391466010964;980.9215262778961;982.5185659027061;767.6622286194084;776.5146783260462;939.8308304505152;956.6603855631221;958.3962103874584;706.0574133531742;707.9858402831966;708;708.2174197511779;708.8653155900363;14255.28723563828;14272.92863535686;14326.498572550016;14338.35477403509;14374.065685730395;12750.87249127506;12925.482980680761;12950.222986620833;13034.21794692312;13216.074874758771;10828.620998265023;11314.603988860432;11654.238335051827;12006.819897701542;12041.879581204163;3900.1614652849776;3921.4706014687936;3924.803759811998;3947.328954077785;4037.2531081749676;47997.520024800004;48049.27926081111;48381;51170.7441462794;52222.738886305706;10606.734831629272;10654.254202205771;10759.56961721538;10837.566497340018;10881.945590271998;18872.245110195712;18907.621847562972;18924.905375473034;18983.050847457573;19007.809921900604;8268.710595129141;8320.876681648022;8408.2432581068;8496.490210587384;8535.658573657109;4617.584417402382;4810.59109975649;4863.4650188479945;4866.05112003156;4882.218844984785;3235.482322828383;3237.077432931603;3238.951415728766;3247.285597168619;3256.299895522474;579.5045236322967;593.5489028338494;594.4382558482279;602.3976023976024;605.0621536618224;684.0799125176629;696.380221602774;702.8453740177152;714.2999860137095;723.106962900817;9481.857772133424;9653.46905920181;9700.854487182698;9752.804943901083;9783.315167938175;553.2862607236619;575.3757173466836;633.8035209085199;760.1220590218306;769.2000319667595;4495.325701144789;4551.886202844956;4562.771861406916;4577.5193604671695;4644.442666879929;61045.694771526745;61561.69219153876;61766;61874.69062654658;62155.378446215866;53271.73364133232;54965.17552236719;55791.44208557862;56323.71838140865;56513.43486565164;47876.521234787906;48630;49098.75450622795;49298.753506232955;49783.751081244365;9246.907530924873;9269.67556135532;9429.575669095044;9462.526873656427;9862.112409883144;36626.8168659155;36718.26563468769;37674;39326.21347572975;39335.60664393319;45065.54934450614;46448.767756161;46468.53531464777;48030.75984620055;48232.7588362056;11202.43987800623;11625.825612615648;12027.518899244282;12113.083262544704;12504.749905001667;13021.674458138623;13061.21632702041;13742.244176570384;13888.305584720521;14310.713785724436;12229.14395992272;12816.935915320364;12921.870781292067;13303.26832024248;13682.042257041912;12146.514139434677;12601.828927402052;12760.74478510406;12786.936065319614;13173.736525269249;10681;11768.882311176778;12352.938235308766;12382.318972456602;13012.5445609407;10747.355158690503;11174.66476005721;11191.216614836883;11260.718482038015;11343.205975581961;10572.048515633629;10868.891311086787;11058.723531911766;11077.889221107685;11184.105271578417 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,846 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: __startTimestamp1455213710536__endTimestamp 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,548 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5482.79 Median:5747.11 stddev:612.45 (10.7%) stddev-sans-first:589.55 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: Values: 3284.6 3320.2 3353.5 3360.3 3381.2 5470.4 5474.3 5500.2 5500.6 5515.1 5448.6 5453.5 5487.1 5494.6 5501.9 4807.5 5020.0 5067.5 5094.1 5111.8 4889.7 4928.2 4934.9 4944.0 4949.1 4842.7 4899.7 4933.4 4939.4 4940.2 4937.6 4966.2 5033.4 5068.3 5072.6 5046.1 5068.7 5123.4 5131.9 5148.4 5036.6 5135.1 5217.1 5335.8 5368.2 5883.9 5897.3 5904.7 5938.7 5940.0 5932.4 5960.6 5961.2 5966.6 5970.2 5200.6 5307.4 5448.2 5522.5 5568.5 6003.3 6056.9 6062.9 6081.7 6107.8 5851.6 5866.6 5870.2 5878.6 5891.1 5688.1 5764.5 5797.1 5900.3 5950.2 5869.5 5895.5 5895.9 5910.8 5913.7 5863.0 5897.2 5904.2 5908.8 5918.1 4399.3 4422.5 4470.6 4535.5 4536.4 4086.9 4091.2 4115.8 4116.4 4139.8 5715.1 5739.7 5743.7 5747.1 5779.3 5599.8 5694.2 5722.0 5808.1 5859.1 5623.6 5626.4 5627.2 5647.9 5649.7 4927.9 5141.8 5144.2 5147.3 5176.5 5973.9 5982.7 6081.9 6083.8 6121.8 5883.0 5889.4 5901.2 5901.6 5903.3 5839.1 5878.6 6128.3 6134.2 6138.8 5921.3 6016.8 6018.7 6022.5 6053.3 5933.6 5974.4 5983.5 5984.1 5993.0 5443.1 5558.1 5761.0 5838.4 5844.6 5853.0 5860.7 5861.2 5863.7 5877.3 5761.3 5762.6 5766.7 5770.8 5772.1 5666.2 5759.8 5770.2 5857.9 5873.2 5600.2 5742.7 5773.4 5777.8 5778.9 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,549 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51109.91 Median:362262.50 stddev:82868.89 (22.9%) stddev-sans-first:83014.48 10:01:50 INFO - 2016-02-11 10:01:50,550 DEBUG : BROWSER_OUTPUT: Values: 572.1 604.2 610.6 614.8 619.4 29727.3 41458.6 41667.8 41698.6 41724.0 46358.5 47069.0 47226.0 47287.3 47305.3 7299.0 7433.9 7434.9 9031.3 9181.8 10688.4 10902.9 10945.8 10958.1 11007.3 10682.7 10733.2 10813.7 10820.8 10826.8 10729.0 10810.5 10825.5 10842.9 10843.6 10521.4 10656.0 10657.4 10679.7 10687.6 42003.0 42451.8 42588.9 42687.8 42709.4 3339.6 3388.8 3466.9 3493.7 3509.6 32474.2 32754.8 32849.2 32948.0 32959.7 3871.7 3932.8 3997.4 4020.0 4105.7 305075.5 305354.0 305562.0 306263.0 306389.0 58945.1 59420.7 67612.0 68138.7 72463.0 66390.3 66420.3 66685.7 66775.0 67050.0 18145.9 18274.9 18298.5 18309.4 18401.5 12932.3 12990.5 13033.9 13040.2 13087.7 21816.3 22010.6 22057.8 22157.6 22162.8 8329.9 8473.1 8524.1 8647.9 8708.7 980.7 992.1 1132.2 1138.4 1144.3 30012.4 30141.6 30859.2 31590.5 32901.7 1084.3 1084.3 1095.1 1118.8 1211.2 13788.7 15012.3 15125.8 15376.6 16493.0 359398.0 361707.0 362818.0 363280.0 363839.0 50296.2 50363.5 50641.5 50738.0 50827.0 1364.0 1377.1 1379.1 1379.8 1387.5 28781.4 28911.0 29076.7 29078.4 29485.9 47301.0 47312.8 47497.3 47598.3 47693.0 248437.0 271167.0 272720.0 299070.0 301414.0 47136.8 47191.0 47377.8 48188.5 49804.3 51153.5 51224.0 51702.7 51783.7 52201.5 41563.0 42749.6 42865.6 42975.8 43654.3 39468.8 46892.0 47708.0 48130.5 48718.5 43254.6 43708.1 45910.3 47327.0 47583.8 36447.8 38846.4 38882.0 39137.8 39567.0 28762.4 28939.0 29196.3 29441.4 29609.7 10:01:50 INFO - 2016-02-11 10:01:50,551 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,551 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26923.75 Median:19194.60 stddev:83199.67 (433.5%) stddev-sans-first:83409.21 10:01:50 INFO - 2016-02-11 10:01:50,551 DEBUG : BROWSER_OUTPUT: Values: 390.4 391.2 392.9 394.0 397.1 17578.0 19131.8 19242.3 19248.0 19835.3 17044.1 17084.7 17097.4 17211.1 17957.7 5604.7 5736.2 5739.3 5743.3 5745.1 5617.6 5681.5 5687.0 5709.2 5731.6 4141.3 4249.4 5629.3 5640.6 5640.7 5981.4 6109.6 6164.8 6173.3 6174.3 5189.1 5346.4 5362.3 5419.7 5459.4 12652.2 13090.3 16236.0 19038.7 20618.3 2267.9 2270.6 2386.7 2389.7 2477.7 9734.6 9984.4 10143.7 10165.5 10313.9 1969.2 2079.6 2163.9 2173.1 2184.8 66986.0 67406.7 68153.0 68705.7 70367.3 10952.9 10998.5 11011.7 11023.0 11047.8 14660.9 14733.3 14736.8 14744.7 14784.6 7493.3 7508.4 7988.8 8041.6 8044.8 7684.9 7976.4 7982.6 8012.8 8028.9 4756.5 4784.7 4799.8 4803.3 4837.0 1913.3 1981.6 2000.3 2017.5 2020.1 416.4 431.5 432.8 913.7 1132.1 10580.2 10870.9 11381.0 11412.4 11494.0 460.6 470.2 475.8 485.6 486.2 5337.0 6048.0 6131.1 6238.2 6357.5 133443.3 467766.0 469021.0 641498.0 643857.0 47757.3 71076.3 82758.6 85774.6 85805.6 500.2 512.3 515.0 515.2 515.3 11046.1 15786.5 15825.3 15871.7 16660.5 35999.0 36374.5 36765.8 37378.0 37998.8 43126.8 44808.6 46796.5 46860.5 48856.5 10351.1 10391.2 10667.8 10681.9 10715.9 12364.5 12450.9 16811.5 18867.9 19638.4 15528.1 15634.4 15896.0 16012.8 16090.9 14195.3 14727.6 14921.9 15657.9 15879.8 15107.5 15383.0 15392.7 15724.5 15724.8 7877.7 9494.4 9989.9 10010.1 10026.9 4890.7 10719.7 13499.9 15614.7 15903.4 10:01:50 INFO - 2016-02-11 10:01:50,551 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,551 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5761.22 Median:5665.85 stddev:3015.19 (53.2%) stddev-sans-first:3007.30 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: Values: 1584.5 1608.8 1627.7 1840.6 1926.0 8106.1 8325.7 8326.1 8328.3 8333.8 6878.8 7157.9 7453.9 7556.4 7749.8 2324.3 2339.9 2345.5 2355.7 2364.6 2049.7 2373.9 2375.3 2436.5 2438.9 2026.0 2290.0 2319.0 2327.9 2328.9 2292.1 2294.3 2294.5 2298.6 2355.2 2338.2 2360.0 2390.7 2391.3 2392.7 8198.9 8293.7 8297.3 8303.9 8305.6 4820.5 4911.3 4946.8 4951.6 4963.7 3162.8 3205.6 3254.3 3275.2 3298.1 1109.1 1116.8 1127.0 1152.3 1154.0 11300.7 11340.6 11367.9 11405.8 11422.7 4284.8 4292.9 4312.4 4343.5 4365.0 9767.3 9768.1 9771.4 9774.6 9778.0 4808.8 4821.3 4842.3 4852.6 4855.2 4375.1 4401.1 4429.2 4437.5 4438.3 3774.6 3814.7 3845.1 3849.7 3852.3 1029.0 1083.0 1138.0 1151.6 1166.1 1095.9 1146.3 1158.0 1173.3 1210.2 7772.1 7822.9 7858.1 7880.4 7912.0 1213.6 1219.5 1224.9 1229.3 1258.0 6368.0 6376.1 6444.0 6452.6 6467.3 9780.0 10001.9 10024.6 10042.5 10138.9 9670.3 9931.5 9942.2 10009.5 10028.1 7767.7 7784.7 7816.9 7831.6 7907.4 7396.1 7423.2 7439.7 7456.1 7519.2 8783.6 8921.3 8957.5 8966.7 8981.0 8933.3 9108.2 9184.8 9218.8 9353.8 7052.8 7138.8 7143.8 7153.3 7155.3 7897.1 7928.4 8041.7 8049.8 8061.3 6607.6 7239.5 7310.5 7373.4 7494.2 6955.6 7508.0 7526.0 7568.2 7606.9 7466.3 7489.6 7497.0 7505.8 7510.9 6629.0 7013.1 7043.4 7057.2 7063.8 7003.9 7005.6 7085.1 7104.1 7110.5 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5460.41 Median:6245.81 stddev:1629.03 (26.1%) stddev-sans-first:1587.62 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: Values: 340.3 352.8 358.5 359.8 363.3 6060.5 6071.5 6089.6 6113.8 6114.8 5752.9 5870.6 5945.3 6064.0 6065.5 4736.8 4750.4 4750.7 4750.8 4753.1 4522.8 4757.5 4761.7 4778.3 4784.6 4588.0 4588.8 4714.2 4726.2 4728.6 4677.6 4694.6 4698.3 4699.8 4705.9 4162.6 4169.0 4170.8 4174.1 4177.2 5670.1 5761.4 5766.6 5769.5 5782.0 5429.8 5436.6 5439.7 5442.0 5446.0 6506.1 6514.9 6520.4 6530.5 6532.2 4514.1 4514.6 4517.5 4520.4 4532.4 6558.0 6649.3 6695.3 6709.5 6721.9 4642.2 4643.9 4644.9 4647.9 4651.6 4697.1 4697.6 4702.8 4705.3 4709.6 5426.6 5448.4 5448.8 5449.6 5461.6 6399.4 6518.1 6550.4 6555.3 6556.0 1689.0 1704.2 1705.9 1706.3 1706.5 1164.6 1167.5 1173.1 1176.8 1185.5 6338.2 6363.6 6373.1 6374.1 6376.8 6454.2 6672.8 6680.3 6682.1 6685.8 6219.8 6271.8 6380.3 6380.7 6382.8 3597.8 3626.8 3634.3 3638.7 3638.8 6830.8 7003.1 7004.8 7009.6 7015.5 6908.7 6920.0 6920.4 6925.1 6928.7 6999.4 7000.9 7006.2 7013.3 7014.0 6584.0 6683.3 6683.9 6697.5 6702.5 6638.1 6646.0 6652.2 6659.3 6663.3 6967.1 6994.7 6994.8 7007.7 7009.6 4533.2 4758.9 4764.0 4767.4 4770.7 6711.7 6718.7 6736.7 6744.3 6744.6 6541.8 6561.0 6563.3 6563.6 6568.8 6405.3 6534.8 6537.7 6546.1 6550.5 6431.4 6441.5 6446.7 6451.1 6456.2 6560.4 6566.3 6568.5 6569.2 6572.9 6307.7 6409.3 6409.8 6411.1 6428.5 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: 10:01:50 INFO - 2016-02-11 10:01:50,553 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14810.27 Median:4631.01 stddev:16887.11 (364.7%) stddev-sans-first:16902.09 10:01:50 INFO - 2016-02-11 10:01:50,554 DEBUG : BROWSER_OUTPUT: Values: 881.8 884.3 884.7 897.1 922.1 13882.3 14307.4 14359.0 14370.2 14383.2 13351.0 13375.9 13398.0 13455.5 13475.0 733.9 738.9 913.1 922.2 936.3 921.6 922.1 929.7 930.3 930.3 974.7 979.7 980.5 980.9 982.5 767.7 776.5 939.8 956.7 958.4 706.1 708.0 708.0 708.2 708.9 14255.3 14272.9 14326.5 14338.4 14374.1 12750.9 12925.5 12950.2 13034.2 13216.1 10828.6 11314.6 11654.2 12006.8 12041.9 3900.2 3921.5 3924.8 3947.3 4037.3 47997.5 48049.3 48381.0 51170.7 52222.7 10606.7 10654.3 10759.6 10837.6 10881.9 18872.2 18907.6 18924.9 18983.1 19007.8 8268.7 8320.9 8408.2 8496.5 8535.7 4617.6 4810.6 4863.5 4866.1 4882.2 3235.5 3237.1 3239.0 3247.3 3256.3 579.5 593.5 594.4 602.4 605.1 684.1 696.4 702.8 714.3 723.1 9481.9 9653.5 9700.9 9752.8 9783.3 553.3 575.4 633.8 760.1 769.2 4495.3 4551.9 4562.8 4577.5 4644.4 61045.7 61561.7 61766.0 61874.7 62155.4 53271.7 54965.2 55791.4 56323.7 56513.4 47876.5 48630.0 49098.8 49298.8 49783.8 9246.9 9269.7 9429.6 9462.5 9862.1 36626.8 36718.3 37674.0 39326.2 39335.6 45065.5 46448.8 46468.5 48030.8 48232.8 11202.4 11625.8 12027.5 12113.1 12504.7 13021.7 13061.2 13742.2 13888.3 14310.7 12229.1 12816.9 12921.9 13303.3 13682.0 12146.5 12601.8 12760.7 12786.9 13173.7 10681.0 11768.9 12352.9 12382.3 13012.5 10747.4 11174.7 11191.2 11260.7 11343.2 10572.0 10868.9 11058.7 11077.9 11184.1 10:01:50 INFO - 2016-02-11 10:01:50,554 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:01:50 INFO - 2016-02-11 10:01:50,554 DEBUG : BROWSER_OUTPUT: 10:01:51 INFO - 2016-02-11 10:01:51,133 INFO : Browser exited with error code: 0 10:01:51 INFO - 2016-02-11 10:01:51,152 INFO : Completed test dromaeo_css (00:18:29) 10:01:51 INFO - 2016-02-11 10:01:51,152 INFO : Starting test kraken 10:01:51 INFO - 2016-02-11 10:01:51,152 DEBUG : operating with platform_type : linux_ 10:01:51 INFO - 2016-02-11 10:01:51,152 INFO : Initialising browser for kraken test... 10:01:51 INFO - 2016-02-11 10:01:51,158 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpep8iJT/profile http://localhost:39161/getInfo.html 10:01:54 INFO - 2016-02-11 10:01:54,960 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:01:54 INFO - 2016-02-11 10:01:54,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:01:54 INFO - 2016-02-11 10:01:54,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:01:54 INFO - 2016-02-11 10:01:54,960 DEBUG : BROWSER_OUTPUT: __metrics 10:01:55 INFO - 2016-02-11 10:01:55,109 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:01:56 INFO - 2016-02-11 10:01:56,185 INFO : Browser initialized. 10:01:56 INFO - 2016-02-11 10:01:56,186 INFO : Running cycle 1/1 for kraken test... 10:01:56 INFO - 2016-02-11 10:01:56,186 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpep8iJT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:02:07 INFO - 2016-02-11 10:02:07,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 134283264 10:02:07 INFO - 2016-02-11 10:02:07,916 DEBUG : BROWSER_OUTPUT: 10:02:10 INFO - 2016-02-11 10:02:10,336 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39161/tests/kraken/driver.html?testName=audio-beat-detection) 10:02:10 INFO - 2016-02-11 10:02:10,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 163446784 10:02:10 INFO - 2016-02-11 10:02:10,904 DEBUG : BROWSER_OUTPUT: 10:02:13 INFO - 2016-02-11 10:02:13,481 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39161/tests/kraken/driver.html?testName=audio-dft) 10:02:14 INFO - 2016-02-11 10:02:14,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 161959936 10:02:14 INFO - 2016-02-11 10:02:14,047 DEBUG : BROWSER_OUTPUT: 10:02:16 INFO - 2016-02-11 10:02:16,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39161/tests/kraken/driver.html?testName=audio-fft) 10:02:17 INFO - 2016-02-11 10:02:17,406 DEBUG : BROWSER_OUTPUT: RSS: Main: 157773824 10:02:17 INFO - 2016-02-11 10:02:17,406 DEBUG : BROWSER_OUTPUT: 10:02:19 INFO - 2016-02-11 10:02:19,378 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39161/tests/kraken/driver.html?testName=audio-oscillator) 10:02:19 INFO - 2016-02-11 10:02:19,945 DEBUG : BROWSER_OUTPUT: RSS: Main: 161480704 10:02:19 INFO - 2016-02-11 10:02:19,945 DEBUG : BROWSER_OUTPUT: 10:02:22 INFO - 2016-02-11 10:02:22,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39161/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:02:22 INFO - 2016-02-11 10:02:22,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 158564352 10:02:22 INFO - 2016-02-11 10:02:22,676 DEBUG : BROWSER_OUTPUT: 10:02:25 INFO - 2016-02-11 10:02:25,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39161/tests/kraken/driver.html?testName=imaging-darkroom) 10:02:26 INFO - 2016-02-11 10:02:26,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 158744576 10:02:26 INFO - 2016-02-11 10:02:26,076 DEBUG : BROWSER_OUTPUT: 10:02:29 INFO - 2016-02-11 10:02:29,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39161/tests/kraken/driver.html?testName=imaging-desaturate) 10:02:29 INFO - 2016-02-11 10:02:29,977 DEBUG : BROWSER_OUTPUT: RSS: Main: 159559680 10:02:29 INFO - 2016-02-11 10:02:29,977 DEBUG : BROWSER_OUTPUT: 10:02:32 INFO - 2016-02-11 10:02:32,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39161/tests/kraken/driver.html?testName=json-parse-financial) 10:02:33 INFO - 2016-02-11 10:02:33,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 157622272 10:02:33 INFO - 2016-02-11 10:02:33,237 DEBUG : BROWSER_OUTPUT: 10:02:34 INFO - 2016-02-11 10:02:34,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39161/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:02:35 INFO - 2016-02-11 10:02:35,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 164167680 10:02:35 INFO - 2016-02-11 10:02:35,352 DEBUG : BROWSER_OUTPUT: 10:02:37 INFO - 2016-02-11 10:02:37,103 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:02:37 INFO - 2016-02-11 10:02:37,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 161939456 10:02:37 INFO - 2016-02-11 10:02:37,692 DEBUG : BROWSER_OUTPUT: 10:02:39 INFO - 2016-02-11 10:02:39,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:02:40 INFO - 2016-02-11 10:02:40,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 167833600 10:02:40 INFO - 2016-02-11 10:02:40,221 DEBUG : BROWSER_OUTPUT: 10:02:42 INFO - 2016-02-11 10:02:42,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:02:43 INFO - 2016-02-11 10:02:43,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 171593728 10:02:43 INFO - 2016-02-11 10:02:43,167 DEBUG : BROWSER_OUTPUT: 10:02:45 INFO - 2016-02-11 10:02:45,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:02:46 INFO - 2016-02-11 10:02:46,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 169353216 10:02:46 INFO - 2016-02-11 10:02:46,209 DEBUG : BROWSER_OUTPUT: 10:02:47 INFO - 2016-02-11 10:02:47,879 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39161/tests/kraken/driver.html?testName=ai-astar) 10:02:48 INFO - 2016-02-11 10:02:48,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 225820672 10:02:48 INFO - 2016-02-11 10:02:48,126 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,129 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:02:48 INFO - 2016-02-11 10:02:48,129 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:02:48 INFO - 2016-02-11 10:02:48,129 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:02:48 INFO - 2016-02-11 10:02:48,129 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;104;108;109;109;101;109;109;101 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;123;153;158;127;151;155;123;158;121 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;162;163;159;161;161;161;160;161;159 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;159;72;73;72;72;162;70 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;142;93;92;93;137;92;93;135 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;110;110 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;146;147;146;146;145;146 10:02:48 INFO - 2016-02-11 10:02:48,130 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;95;96;96;96 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;58;59;57;58;58;57;58;56;56 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;65;65;68;65;65;66;65 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;78;78;80;78;78;78;78;78;78 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;118;110;117;116;114;124;125;111;126 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;132;141;157;139;141;139;140;139;140 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;66;67;66;62;69;63;64;64;62 10:02:48 INFO - 2016-02-11 10:02:48,131 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:02:48 INFO - 2016-02-11 10:02:48,132 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:02:48 INFO - 2016-02-11 10:02:48,132 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,903 10:02:48 INFO - 2016-02-11 10:02:48,132 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:02:48 INFO - 2016-02-11 10:02:48,132 DEBUG : BROWSER_OUTPUT: __startTimestamp1455213768127__endTimestamp 10:02:48 INFO - 2016-02-11 10:02:48,132 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,135 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:02:48 INFO - 2016-02-11 10:02:48,135 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:3.37 (3.1%) stddev-sans-first:3.54 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 104.0 108.0 109.0 109.0 101.0 109.0 109.0 101.0 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:152.00 stddev:16.17 (10.6%) stddev-sans-first:16.82 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: Values: 151.0 123.0 153.0 158.0 127.0 151.0 155.0 123.0 158.0 121.0 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.30 10:02:48 INFO - 2016-02-11 10:02:48,136 DEBUG : BROWSER_OUTPUT: Values: 160.0 162.0 163.0 159.0 161.0 161.0 161.0 160.0 161.0 159.0 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.50 Median:72.00 stddev:37.44 (52.0%) stddev-sans-first:39.10 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 159.0 72.0 73.0 72.0 72.0 162.0 70.0 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.50 stddev:22.01 (23.8%) stddev-sans-first:22.74 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 142.0 93.0 92.0 93.0 137.0 92.0 93.0 135.0 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 146.0 147.0 146.0 146.0 145.0 146.0 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:02:48 INFO - 2016-02-11 10:02:48,137 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: Values: 57.0 58.0 59.0 57.0 58.0 58.0 57.0 58.0 56.0 56.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.12 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 65.0 65.0 68.0 65.0 65.0 66.0 65.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:0.94 (1.2%) stddev-sans-first:0.67 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: Values: 76.0 78.0 78.0 80.0 78.0 78.0 78.0 78.0 78.0 78.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.40 Median:117.50 stddev:5.82 (4.9%) stddev-sans-first:5.95 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: Values: 113.0 118.0 110.0 117.0 116.0 114.0 124.0 125.0 111.0 126.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.30 Median:140.50 stddev:6.38 (4.5%) stddev-sans-first:6.62 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: Values: 145.0 132.0 141.0 157.0 139.0 141.0 139.0 140.0 139.0 140.0 10:02:48 INFO - 2016-02-11 10:02:48,138 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,139 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:2.32 (3.6%) stddev-sans-first:2.39 10:02:48 INFO - 2016-02-11 10:02:48,139 DEBUG : BROWSER_OUTPUT: Values: 63.0 66.0 67.0 66.0 62.0 69.0 63.0 64.0 64.0 62.0 10:02:48 INFO - 2016-02-11 10:02:48,139 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:02:48 INFO - 2016-02-11 10:02:48,139 DEBUG : BROWSER_OUTPUT: 10:02:48 INFO - 2016-02-11 10:02:48,834 INFO : Browser exited with error code: 0 10:02:48 INFO - 2016-02-11 10:02:48,891 INFO : Completed test kraken (00:00:57) 10:02:48 INFO - 2016-02-11 10:02:48,891 INFO : Starting test v8_7 10:02:48 INFO - 2016-02-11 10:02:48,891 DEBUG : operating with platform_type : linux_ 10:02:48 INFO - 2016-02-11 10:02:48,892 INFO : Initialising browser for v8_7 test... 10:02:48 INFO - 2016-02-11 10:02:48,898 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPgVf_k/profile http://localhost:39161/getInfo.html 10:02:52 INFO - 2016-02-11 10:02:52,638 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:02:52 INFO - 2016-02-11 10:02:52,638 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:02:52 INFO - 2016-02-11 10:02:52,638 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:02:52 INFO - 2016-02-11 10:02:52,638 DEBUG : BROWSER_OUTPUT: __metrics 10:02:52 INFO - 2016-02-11 10:02:52,787 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:02:53 INFO - 2016-02-11 10:02:53,801 INFO : Browser initialized. 10:02:53 INFO - 2016-02-11 10:02:53,802 INFO : Running cycle 1/1 for v8_7 test... 10:02:53 INFO - 2016-02-11 10:02:53,802 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPgVf_k/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:03:05 INFO - 2016-02-11 10:03:05,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 133537792 10:03:05 INFO - 2016-02-11 10:03:05,496 DEBUG : BROWSER_OUTPUT: 10:03:26 INFO - 2016-02-11 10:03:26,846 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:39161/tests/v8_7/run.html (next: http://localhost:39161/tests/v8_7/run.html) 10:03:27 INFO - 2016-02-11 10:03:27,104 DEBUG : BROWSER_OUTPUT: RSS: Main: 426725376 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:03:27 INFO - 2016-02-11 10:03:27,105 DEBUG : BROWSER_OUTPUT: |0;Richards;165.50810989738497 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.44486108743823 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5175.257731958763 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |4;RayTrace;928.5051067780872 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |5;Earley;537.6344086021505 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |6;Boyer;11043.956043956045 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |7;RegExp;24214.285714285714 10:03:27 INFO - 2016-02-11 10:03:27,106 DEBUG : BROWSER_OUTPUT: |8;Splay;527.1481286241434 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: __startTimestamp1455213807098__endTimestamp 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:03:27 INFO - 2016-02-11 10:03:27,107 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.51 Median:165.51 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: Values: 165.5 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.44 Median:170.44 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: Values: 170.4 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,108 DEBUG : BROWSER_OUTPUT: Values: 278.8 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: Values: 5175.3 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:928.51 Median:928.51 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: Values: 928.5 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:537.63 Median:537.63 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: Values: 537.6 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,109 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11043.96 Median:11043.96 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: Values: 11044.0 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24214.29 Median:24214.29 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: Values: 24214.3 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:527.15 Median:527.15 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: Values: 527.1 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: Values: 4835.7 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:03:27 INFO - 2016-02-11 10:03:27,110 DEBUG : BROWSER_OUTPUT: 10:03:27 INFO - 2016-02-11 10:03:27,758 INFO : Browser exited with error code: 0 10:03:27 INFO - 2016-02-11 10:03:27,772 INFO : Completed test v8_7 (00:00:38) 10:03:28 INFO - 2016-02-11 10:03:28,168 INFO : Completed test suite (00:20:06) 10:03:28 INFO - 2016-02-11 10:03:28,168 DEBUG : Working with test: dromaeo_css 10:03:28 INFO - 2016-02-11 10:03:28,169 DEBUG : Generating results file: dromaeo_css 10:03:28 INFO - 2016-02-11 10:03:28,170 DEBUG : Working with test: kraken 10:03:28 INFO - 2016-02-11 10:03:28,170 DEBUG : Generating results file: kraken 10:03:28 INFO - 2016-02-11 10:03:28,170 INFO : javascript benchmark 10:03:28 INFO - 2016-02-11 10:03:28,170 DEBUG : Working with test: v8_7 10:03:28 INFO - 2016-02-11 10:03:28,170 DEBUG : Generating results file: v8_7 10:03:28 INFO - 2016-02-11 10:03:28,171 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:03:28 INFO - 2016-02-11 10:03:28,229 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:03:28 INFO - 2016-02-11 10:03:28,230 DEBUG : process_Request line: dromaeo_css 7302.78 graph.html#tests=[[72,53,35]] 10:03:28 INFO - 2016-02-11 10:03:28,230 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:03:28 INFO - 2016-02-11 10:03:28,260 DEBUG : process_Request line: kraken 1479.40 graph.html#tests=[[232,53,35]] 10:03:28 INFO - 2016-02-11 10:03:28,260 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:03:28 INFO - 2016-02-11 10:03:28,389 DEBUG : process_Request line: v8_7 23188.65 graph.html#tests=[[230,53,35]] 10:03:28 INFO - 2016-02-11 10:03:28,390 INFO : javascript benchmark 10:03:28 INFO - 2016-02-11 10:03:28,393 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3284.573005509284, 3320.2197483591335, 3353.5472711183997, 3360.2607426366208, 3381.1547113221695, 5470.444280853946, 5474.315710536183, 5500.1749737539385, 5500.642458240217, 5515.117581187011, 5448.591355648323, 5453.481919217668, 5487.067198576242, 5494.560435165187, 5501.862453438666, 4807.519248075199, 5020.021095886304, 5067.543921047105, 5094.057599344133, 5111.7699703513435, 4889.6821706589135, 4928.186849169882, 4934.851954441355, 4944.0, 4949.059678661061, 4842.685225460334, 4899.657024008321, 4933.358663373774, 4939.43196532398, 4940.2342636891335, 4937.604991600663, 4966.180580204262, 5033.370828646419, 5068.26510156029, 5072.594192464594, 5046.066477701637, 5068.746562671889, 5123.385193776733, 5131.923021154686, 5148.3564554430695, 5036.571891389236, 5135.075686376451, 5217.087009773274, 5335.813246536352, 5368.221607866876, 5883.941160588382, 5897.292324920994, 5904.704764761745, 5938.732757025901, 5939.970300148515, 5932.406759324076, 5960.552958528127, 5961.225040744719, 5966.642001479881, 5970.164177015221, 5200.557952574036, 5307.3896501902, 5448.237246785453, 5522.475364840334, 5568.470995255444, 6003.339632640406, 6056.939430605726, 6062.9090563641585, 6081.665508396992, 6107.755689772448, 5851.61964472308, 5866.6186697864505, 5870.178175055496, 5878.559108066912, 5891.116332550152, 5688.061469857427, 5764.481196692234, 5797.130430435468, 5900.291964964146, 5950.226470558886, 5869.530437564985, 5895.498882595028, 5895.911561326584, 5910.822675319747, 5913.733881975321, 5862.970685146547, 5897.174395584621, 5904.232449781501, 5908.763649454059, 5918.082697181858, 4399.340098985178, 4422.513523512411, 4470.642348612103, 4535.523770004102, 4536.387587675687, 4086.877393678135, 4091.243120022805, 4115.814788334532, 4116.403121547359, 4139.834406623761, 5715.142728590661, 5739.713014349266, 5743.655380677173, 5747.109198074305, 5779.335376431764, 5599.776008959678, 5694.202811606378, 5722.0, 5808.128780682847, 5859.062549992066, 5623.634463759842, 5626.409227031103, 5627.184058311522, 5647.858803529863, 5649.745761440745, 4927.87680307988, 5141.845744627667, 5144.151215049475, 5147.305113809588, 5176.50823171798, 5973.910391344133, 5982.700864956735, 6081.939180608226, 6083.7870674526175, 6121.84695382619, 5883.0, 5889.440503152194, 5901.203337549376, 5901.557383196363, 5903.291605007426, 5839.0949402842625, 5878.588498805043, 6128.295246046761, 6134.202553667995, 6138.785142519991, 5921.3190483094995, 6016.819495415145, 6018.668973206516, 6022.518198544193, 6053.334133245427, 5933.584649074523, 5974.372690867396, 5983.521318294439, 5984.072468767348, 5992.970035149797, 5443.101888188405, 5558.138488534284, 5761.049426844524, 5838.357780644041, 5844.561657875592, 5853.03424934898, 5860.706964651836, 5861.179434879035, 5863.73613187399, 5877.324107727666, 5761.279840019997, 5762.625429347079, 5766.654000759968, 5770.826875193751, 5772.105323674669, 5666.2350582670815, 5759.798407055733, 5770.2498675171955, 5857.94142058591, 5873.236479257669, 5600.187972744011, 5742.684152371659, 5773.393793651606, 5777.7977770777825, 5778.855528611818], "unit": "ms", "name": "dojo.html", "value": 5443.362581688902}, {"lowerIsBetter": false, "replicates": [572.0578635532368, 604.1722839709596, 610.5909040942569, 614.7510258345369, 619.3837132053608, 29727.256818579546, 41458.585414145884, 41667.79166104165, 41698.583014169846, 41723.99999999996, 46358.53641463584, 47069.0, 47226.0, 47287.29069063958, 47305.29042064363, 7298.9635051824935, 7433.851322973537, 7434.888476672854, 9031.3226508012, 9181.816363672722, 10688.412137332443, 10902.945485272563, 10945.781084378308, 10958.12335013201, 11007.339559626447, 10682.732931676692, 10733.248672592921, 10813.729656758565, 10820.783584328348, 10826.837597436044, 10729.0, 10810.513526891309, 10825.512851921643, 10842.945785271062, 10843.620473283476, 10521.421321827296, 10656.04095631397, 10657.360558366445, 10679.679609611723, 10687.572497100107, 42003.0, 42451.78774106141, 42588.870556472364, 42687.7865610673, 42709.35935960963, 3339.5825521809775, 3388.81361525116, 3466.878659246914, 3493.737969652286, 3509.5613048368955, 32474.188145296557, 32754.83622581872, 32849.17877053069, 32948.0, 32959.6704032959, 3871.7096217783496, 3932.823022963945, 3997.400389941503, 4020.035191554006, 4105.651019663333, 305075.47462262766, 305354.0, 305562.0, 306263.0, 306389.0, 58945.115823262684, 59420.70289648524, 67612.0, 68138.65930670315, 72463.0, 66390.33609663938, 66420.33579664142, 66685.66657166684, 66775.0, 67050.0, 18145.909270453827, 18274.90862545679, 18298.45104646863, 18309.3591724289, 18401.53996150107, 12932.288724120264, 12990.545330913372, 13033.869661303266, 13040.152390094614, 13087.672808179872, 21816.345509634735, 22010.559788804454, 22057.779422205895, 22157.556848863256, 22162.778372216395, 8329.916700833035, 8473.110323416076, 8524.104968978292, 8647.870281945776, 8708.651653933772, 980.7401038724744, 992.0823238504499, 1132.1961407400672, 1138.3568283919558, 1144.250515912079, 30012.399752004836, 30141.56693951648, 30859.228519287197, 31590.526142107887, 32901.67098328986, 1084.3331351218944, 1084.348146451729, 1095.052779345876, 1118.759466714644, 1211.2284962155336, 13788.655283617789, 15012.324445399983, 15125.773113403307, 15376.615584610474, 16493.0, 359398.0, 361707.0, 362818.0, 363280.0, 363839.0, 50296.24555631669, 50363.496365035884, 50641.49358506442, 50738.0, 50827.0, 1363.9522616708368, 1377.1392879450343, 1379.1449301432974, 1379.7585422551012, 1387.4796951143321, 28781.424371512032, 28911.0, 29076.7092329074, 29078.418431630827, 29485.85257073787, 47301.0, 47312.7634361826, 47497.28754068761, 47598.28602571033, 47693.0, 248437.0, 271167.0, 272720.0, 299070.0, 301414.0, 47136.764316178196, 47191.0, 47377.76311118422, 48188.5181148198, 49804.25293620526, 51153.488465114875, 51224.0, 51702.741486292325, 51783.74108129435, 52201.47798521966, 41563.0, 42749.572504275806, 42865.57134428616, 42975.785121074194, 43654.34518482162, 39468.80265598654, 46892.0, 47708.0, 48130.51869481261, 48718.5128148714, 43254.567454326316, 43708.12583748244, 45910.31134532918, 47327.0, 47583.76208118937, 36447.81776091103, 38846.4173037399, 38882.0, 39137.80431097826, 39567.0, 28762.42475150527, 28939.0, 29196.27009324784, 29441.411171776872, 29609.703902961555], "unit": "ms", "name": "ext.html", "value": 20580.46314085227}, {"lowerIsBetter": false, "replicates": [390.38709226514385, 391.2292783217063, 392.9057026313684, 393.97833119178443, 397.1223595853164, 17578.0, 19131.808681913175, 19242.326518571852, 19248.0, 19835.305764298253, 17044.147792610383, 17084.743728844078, 17097.40159094432, 17211.13944302786, 17957.7306340405, 5604.719764011793, 5736.1682556029355, 5739.339975902762, 5743.3108027036815, 5745.138229265602, 5617.578681598876, 5681.460261275193, 5686.971565142168, 5709.172170035343, 5731.627444216134, 4141.254574176647, 4249.383839343309, 5629.268195134627, 5640.605157638967, 5640.746166422521, 5981.371955944628, 6109.63342199465, 6164.7842325518395, 6173.2900716417735, 6174.320824709278, 5189.143791274435, 5346.438623944468, 5362.276092727471, 5419.729013549307, 5459.3994660587305, 12652.177608455513, 13090.345482725921, 16236.02583844973, 19038.714419283722, 20618.278360257467, 2267.875266860322, 2270.591293567157, 2386.737458879522, 2389.749076346976, 2477.665515155467, 9734.561944712505, 9984.400935943866, 10143.695689129338, 10165.491725413774, 10313.9484302578, 1969.2418418908824, 2079.6048750737464, 2163.891805409739, 2173.076442511926, 2184.803367696915, 66986.0, 67406.66296668485, 68152.97770533495, 68705.65647171783, 70367.29632703659, 10952.890471095347, 10998.450077496253, 11011.724706882233, 11023.0, 11047.779044419227, 14660.853391466162, 14733.263336833115, 14736.778948315783, 14744.705105898036, 14784.630384240265, 7493.250674932463, 7508.436867234979, 7988.760337189894, 8041.6381262843315, 8044.798880027929, 7684.884726729104, 7976.441649084624, 7982.640781164861, 8012.799680008046, 8028.879566806502, 4756.453007904066, 4784.68899521537, 4799.808007679723, 4803.303520989437, 4836.975815120973, 1913.2920819296949, 1981.6234915366035, 2000.2699014859472, 2017.5057111007898, 2020.0808632072371, 416.41077874807416, 431.4520558890255, 432.8398492557774, 913.7213149989184, 1132.0773569540797, 10580.206484513692, 10870.866078908442, 11381.0, 11412.42937853104, 11494.023008044243, 460.617687319524, 470.2358667165856, 475.80016393114977, 485.5702703107775, 486.23660852462, 5336.973315133399, 6048.014543202608, 6131.110988906584, 6238.189035425366, 6357.4914006879335, 133443.33278333547, 467766.0, 469021.0, 641498.0, 643857.0, 47757.283640745416, 71076.28923710696, 82758.58620706978, 85774.57112714396, 85805.57097214473, 500.18469894073155, 512.3262909274367, 515.0317403281881, 515.1628603519281, 515.3352175693313, 11046.116310694964, 15786.526404207432, 15825.287862046005, 15871.68256634884, 16660.50018499447, 35999.0, 36374.454383184806, 36765.81617091897, 37378.0, 37998.810005949796, 43126.78436607797, 44808.551914480435, 46796.532034679214, 46860.53139468562, 48856.51143488519, 10351.120154786928, 10391.168706503311, 10667.839982400115, 10681.946590266998, 10715.946420267848, 12364.505419783467, 12450.875491245333, 16811.495655130366, 18867.90566047161, 19638.410847674593, 15528.058992625922, 15634.374625014872, 15896.0, 16012.839871601602, 16090.919545402197, 14195.290235487977, 14727.631809204855, 14921.85078149248, 15657.921710391374, 15879.841201587837, 15107.54677359681, 15383.0, 15392.69214615679, 15724.528264152093, 15724.76412853831, 7877.684892604231, 9494.430334179971, 9989.900100998897, 10010.099091081838, 10026.949865250626, 4890.706557606555, 10719.678409647722, 13499.920006399268, 15614.687706245584, 15903.363865445253], "unit": "ms", "name": "jquery.html", "value": 8479.920203084172}, {"lowerIsBetter": false, "replicates": [1584.492962252079, 1608.769291492008, 1627.682601892631, 1840.567466645338, 1926.0369815092454, 8106.148854370287, 8325.666973321075, 8326.084130745614, 8328.25045745882, 8333.833323333529, 6878.793636190922, 7157.928420715792, 7453.925460745391, 7556.433267504945, 7749.845003099935, 2324.2562380038353, 2339.883005849709, 2345.4605440748637, 2355.6702061711376, 2364.609839376501, 2049.6515592349338, 2373.9406514837183, 2375.2992867104244, 2436.512697460515, 2438.878056097188, 2026.0376321247363, 2290.015293423827, 2319.0, 2327.8603283803027, 2328.871912044837, 2292.083166733303, 2294.32317466347, 2294.529621427612, 2298.643710224918, 2355.2463211772238, 2338.1699496678602, 2359.9528009439887, 2390.7370189279163, 2391.318474234835, 2392.7487613800477, 8198.918010819874, 8293.709720159826, 8297.336213102937, 8303.875441868317, 8305.626246818847, 4820.517948205186, 4911.287863259808, 4946.752662366904, 4951.579115775164, 4963.70217786934, 3162.794418362822, 3205.6153261608524, 3254.3491301739746, 3275.197576593726, 3298.0600528849404, 1109.1293334732277, 1116.7654792493586, 1126.9965541831011, 1152.2740673375804, 1153.9769204615945, 11300.660980170607, 11340.6030788922, 11367.886321136766, 11405.828912566318, 11422.657320280405, 4284.828606855754, 4292.892677683083, 4312.439382880269, 4343.543927887589, 4364.956350436519, 9767.267454940908, 9768.07203315684, 9771.41371517697, 9774.5601447935, 9777.951110244403, 4808.759562021885, 4821.276808478756, 4842.346283251786, 4852.587530059914, 4855.198892182821, 4375.146846364928, 4401.1417773534495, 4429.247028005247, 4437.511873693891, 4438.312061630452, 3774.6031270143108, 3814.713896457721, 3845.057960799566, 3849.711271654637, 3852.3065848147044, 1028.99485502572, 1083.0469187115445, 1138.004246284501, 1151.6142092398973, 1166.084623570493, 1095.9287646303123, 1146.2893006335958, 1158.0214718562802, 1173.2549830857297, 1210.195220178589, 7772.106207786064, 7822.882656760153, 7858.1356050834365, 7880.448368614257, 7912.010998625172, 1213.6298428979069, 1219.481720268882, 1224.8530176378893, 1229.3361584744316, 1257.9622611321674, 6368.0, 6376.075469056961, 6443.96778016107, 6452.645104519296, 6467.288598254188, 9780.0, 10001.949990250003, 10024.649137280161, 10042.497875106215, 10138.898611013796, 9670.323077384515, 9931.50342482873, 9942.204623630238, 10009.54957026921, 10028.097471227624, 7767.650455729506, 7784.688612455551, 7816.921830781733, 7831.569263690438, 7907.406944479187, 7396.14944281393, 7423.220561841145, 7439.739609113655, 7456.067991501062, 7519.210482899217, 8783.648654054023, 8921.286297096347, 8957.462552246625, 8966.699828524697, 8980.955095224743, 8933.330000250136, 9108.22580080674, 9184.816303674022, 9218.815623687622, 9353.766155846159, 7052.823679408056, 7138.750143744944, 7143.785686429415, 7153.320434558708, 7155.284471553011, 7897.05235371759, 7928.405369597419, 8041.67833286662, 8049.798755031171, 8061.314788243065, 6607.57050791697, 7239.457040721862, 7310.52481588695, 7373.410127189919, 7494.17564067963, 6955.617441040791, 7507.962460187664, 7525.962370188114, 7568.243175682388, 7606.885896711665, 7466.328030477283, 7489.588072656056, 7497.0, 7505.812354691177, 7510.887336689845, 6629.038789375417, 7013.123359580052, 7043.36609705129, 7057.2237053925055, 7063.82340441493, 7003.859922801617, 7005.614691192033, 7085.14978202619, 7104.076470058859, 7110.537815042006], "unit": "ms", "name": "mootools.html", "value": 4715.359167231672}, {"lowerIsBetter": false, "replicates": [340.3074742898202, 352.7795128044972, 358.45335862809213, 359.83447614097514, 363.30971154805883, 6060.515158787298, 6071.514278857692, 6089.573729838913, 6113.755449782004, 6114.847128821782, 5752.942470575293, 5870.589058765888, 5945.256842894638, 6064.0, 6065.545084118698, 4736.834210802623, 4750.4299484061785, 4750.667453278272, 4750.809967601292, 4753.096911586787, 4522.751248681321, 4757.476677565465, 4761.714297142165, 4778.331033655291, 4784.5693887550115, 4588.0, 4588.839390621329, 4714.222153344694, 4726.196546587088, 4728.574428301452, 4677.625789936814, 4694.577488026094, 4698.342232087511, 4699.835505757282, 4705.858824235278, 4162.583741625844, 4168.979155104235, 4170.833166673329, 4174.123434078847, 4177.206330797169, 5670.092785154356, 5761.423857614247, 5766.596338256324, 5769.480746732815, 5782.0, 5429.809956651498, 5436.592255580807, 5439.728013599344, 5442.0476416627325, 5445.9727701361635, 6506.056621789861, 6514.902276465857, 6520.4457621102265, 6530.542861999662, 6532.216134063943, 4514.142312960527, 4514.6388288936805, 4517.457905051415, 4520.367148599199, 4532.388127602764, 6557.967210163919, 6649.3350664933605, 6695.2969938156275, 6709.463242940553, 6721.932780672229, 4642.16441040616, 4643.883902902455, 4644.907101857944, 4647.930281045787, 4651.5813576778255, 4697.060587882369, 4697.553732395417, 4702.835400760957, 4705.294205869079, 4709.552592503706, 5426.62013659048, 5448.427915068861, 5448.83653490396, 5449.5640348772795, 5461.617686761968, 6399.424051835264, 6518.1200537927725, 6550.377714117151, 6555.344465553407, 6556.0493728410065, 1688.9746653800203, 1704.181992643538, 1705.8550023248163, 1706.2918888661068, 1706.4880535839447, 1164.6214980131492, 1167.4571324334056, 1173.061550759389, 1176.7528818948117, 1185.5494911933583, 6338.207724034496, 6363.61818290904, 6373.139626150503, 6374.075759014917, 6376.84057898547, 6454.19322584677, 6672.83317917056, 6680.265170831205, 6682.097916781172, 6685.76599819004, 6219.813405597839, 6271.811845644638, 6380.298167201604, 6380.680965951684, 6382.840428989312, 3597.8021208833766, 3626.7642603230706, 3634.34581775283, 3638.7452878298263, 3638.7816730996224, 6830.795076147624, 7003.054587630707, 7004.754833580801, 7009.614471204132, 7015.473839462061, 6908.6545672716165, 6920.031195632616, 6920.446364290845, 6925.099737034153, 6928.688209030505, 6999.370056694922, 7000.8949865753075, 7006.159260888624, 7013.333733295329, 7014.0, 6583.967080164569, 6683.264840867598, 6683.8997415039785, 6697.53117281805, 6702.497312701665, 6638.103855979574, 6645.966770166118, 6652.234992975869, 6659.334066593302, 6663.300353462817, 6967.09427774386, 6994.6852391643515, 6994.790156295319, 7007.719691212498, 7009.649517524002, 4533.206688829376, 4758.881027974328, 4764.0, 4767.380240568704, 4770.737609431515, 6711.731530738911, 6718.697658605277, 6736.66316684154, 6744.325567443217, 6744.629045402354, 6541.803745887631, 6561.0, 6563.343665633399, 6563.606183628997, 6568.80293591193, 6405.263394709668, 6534.8366290843105, 6537.738490460329, 6546.0508226307875, 6550.541462097607, 6431.356864313531, 6441.5490915635455, 6446.742130314735, 6451.1290975717675, 6456.225252969673, 6560.40956313934, 6566.343365663586, 6568.540202185801, 6569.211694596488, 6572.934270657232, 6307.653079080695, 6409.327020662763, 6409.8397540060005, 6411.102445657519, 6428.4857211423905], "unit": "ms", "name": "prototype.html", "value": 4939.45858977059}, {"lowerIsBetter": false, "replicates": [881.7751473374288, 884.2704768565927, 884.7080463447063, 897.129784109414, 922.1055576091192, 13882.305884705762, 14307.356168972396, 14359.0, 14370.20963846988, 14383.208923509203, 13351.0, 13375.866241337584, 13398.0, 13455.461781528751, 13475.0, 733.9449541284398, 738.9445791565627, 913.1234015345276, 922.1792604581894, 936.2556767369923, 921.6497730862296, 922.0594993107027, 929.7350255177275, 930.3255140023497, 930.3394589841221, 974.6539978307705, 979.6571200079986, 980.5391466010964, 980.9215262778961, 982.5185659027061, 767.6622286194084, 776.5146783260462, 939.8308304505152, 956.6603855631221, 958.3962103874584, 706.0574133531742, 707.9858402831966, 708.0, 708.2174197511779, 708.8653155900363, 14255.28723563828, 14272.92863535686, 14326.498572550016, 14338.35477403509, 14374.065685730395, 12750.87249127506, 12925.482980680761, 12950.222986620833, 13034.21794692312, 13216.074874758771, 10828.620998265023, 11314.603988860432, 11654.238335051827, 12006.819897701542, 12041.879581204163, 3900.1614652849776, 3921.4706014687936, 3924.803759811998, 3947.328954077785, 4037.2531081749676, 47997.520024800004, 48049.27926081111, 48381.0, 51170.7441462794, 52222.738886305706, 10606.734831629272, 10654.254202205771, 10759.56961721538, 10837.566497340018, 10881.945590271998, 18872.245110195712, 18907.621847562972, 18924.905375473034, 18983.050847457573, 19007.809921900604, 8268.710595129141, 8320.876681648022, 8408.2432581068, 8496.490210587384, 8535.658573657109, 4617.584417402382, 4810.59109975649, 4863.4650188479945, 4866.05112003156, 4882.218844984785, 3235.482322828383, 3237.077432931603, 3238.951415728766, 3247.285597168619, 3256.299895522474, 579.5045236322967, 593.5489028338494, 594.4382558482279, 602.3976023976024, 605.0621536618224, 684.0799125176629, 696.380221602774, 702.8453740177152, 714.2999860137095, 723.106962900817, 9481.857772133424, 9653.46905920181, 9700.854487182698, 9752.804943901083, 9783.315167938175, 553.2862607236619, 575.3757173466836, 633.8035209085199, 760.1220590218306, 769.2000319667595, 4495.325701144789, 4551.886202844956, 4562.771861406916, 4577.5193604671695, 4644.442666879929, 61045.694771526745, 61561.69219153876, 61766.0, 61874.69062654658, 62155.378446215866, 53271.73364133232, 54965.17552236719, 55791.44208557862, 56323.71838140865, 56513.43486565164, 47876.521234787906, 48630.0, 49098.75450622795, 49298.753506232955, 49783.751081244365, 9246.907530924873, 9269.67556135532, 9429.575669095044, 9462.526873656427, 9862.112409883144, 36626.8168659155, 36718.26563468769, 37674.0, 39326.21347572975, 39335.60664393319, 45065.54934450614, 46448.767756161, 46468.53531464777, 48030.75984620055, 48232.7588362056, 11202.43987800623, 11625.825612615648, 12027.518899244282, 12113.083262544704, 12504.749905001667, 13021.674458138623, 13061.21632702041, 13742.244176570384, 13888.305584720521, 14310.713785724436, 12229.14395992272, 12816.935915320364, 12921.870781292067, 13303.26832024248, 13682.042257041912, 12146.514139434677, 12601.828927402052, 12760.74478510406, 12786.936065319614, 13173.736525269249, 10681.0, 11768.882311176778, 12352.938235308766, 12382.318972456602, 13012.5445609407, 10747.355158690503, 11174.66476005721, 11191.216614836883, 11260.718482038015, 11343.205975581961, 10572.048515633629, 10868.891311086787, 11058.723531911766, 11077.889221107685, 11184.105271578417], "unit": "ms", "name": "yui.html", "value": 6854.632611988269}], "name": "dromaeo_css", "value": 7302.785141254351}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 104.0, 108.0, 109.0, 109.0, 101.0, 109.0, 109.0, 101.0], "unit": "ms", "name": "ai-astar", "value": 106.7}, {"lowerIsBetter": true, "replicates": [151.0, 123.0, 153.0, 158.0, 127.0, 151.0, 155.0, 123.0, 158.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [160.0, 162.0, 163.0, 159.0, 161.0, 161.0, 161.0, 160.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 159.0, 72.0, 73.0, 72.0, 72.0, 162.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 89.5}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 142.0, 93.0, 92.0, 93.0, 137.0, 92.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 59.0, 57.0, 58.0, 58.0, 57.0, 58.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 65.0, 65.0, 68.0, 65.0, 65.0, 66.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.2}, {"lowerIsBetter": true, "replicates": [76.0, 78.0, 78.0, 80.0, 78.0, 78.0, 78.0, 78.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [113.0, 118.0, 110.0, 117.0, 116.0, 114.0, 124.0, 125.0, 111.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.4}, {"lowerIsBetter": true, "replicates": [145.0, 132.0, 141.0, 157.0, 139.0, 141.0, 139.0, 140.0, 139.0, 140.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.3}, {"lowerIsBetter": true, "replicates": [63.0, 66.0, 67.0, 66.0, 62.0, 69.0, 63.0, 64.0, 64.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.6}], "name": "kraken", "value": 1479.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.50810989738497], "unit": "score", "name": "Richards", "value": 213.294684}, {"lowerIsBetter": false, "replicates": [170.44486108743823], "unit": "score", "name": "DeltaBlue", "value": 387.91430599999984}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [928.5051067780872], "unit": "score", "name": "RayTrace", "value": 796.9681530000001}, {"lowerIsBetter": false, "replicates": [537.6344086021505], "unit": "score", "name": "Earley", "value": 1239.62118}, {"lowerIsBetter": false, "replicates": [11043.956043956045], "unit": "score", "name": "Boyer", "value": 60.346400995024844}, {"lowerIsBetter": false, "replicates": [24214.285714285714], "unit": "score", "name": "RegExp", "value": 37.621799410029524}, {"lowerIsBetter": false, "replicates": [527.1481286241434], "unit": "score", "name": "Splay", "value": 154.588427}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23188.6487830349}]} 10:03:28 INFO - RETURN: dromaeo_css: 7302.78 10:03:28 INFO - RETURN: kraken: 1479.40 10:03:28 INFO - RETURN: v8_7: 23188.65 10:03:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23188.65"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1479.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7302.78"}}} 10:03:28 INFO - Return code: 0 10:03:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:03:28 INFO - # TBPL SUCCESS # 10:03:28 INFO - Running post-action listener: _resource_record_post_action 10:03:28 INFO - Running post-run listener: _resource_record_post_run 10:03:29 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 315392; Write bytes: 503496704; Read time: 44; Write time: 445900 10:03:29 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239611904; Read time: 0; Write time: 310396 10:03:29 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 315392; Write bytes: 263671808; Read time: 44; Write time: 135480 10:03:29 INFO - Running post-run listener: _upload_blobber_files 10:03:29 INFO - Blob upload gear active. 10:03:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:03:29 INFO - Copying logs to upload dir... 10:03:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.486666 ========= master_lag: 111.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 44 secs) (at 2016-02-11 10:05:20.131190) ========= ========= Started set props: build_url (results: 0, elapsed: 19 secs) (at 2016-02-11 10:05:20.134882) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010029 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455202356/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 19.57 ========= ========= Finished set props: build_url (results: 0, elapsed: 19 secs) (at 2016-02-11 10:05:39.719413) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 10:05:39.719769) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1455212554.273157-1030252015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004654 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 10:05:39.908484) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 10:05:39.908806) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 10:05:39.912553) ========= ========= Total master_lag: 130.97 =========