builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1456446695.77 results: success (0) buildid: 20160225135328 builduid: 9adc4a4967034374b69f1c24323b1ac8 revision: 3eaf4e1122e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.767038) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.767543) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.767887) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.003468 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.782957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.783296) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.792105) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.792483) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.003894 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.840771) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.841114) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.841508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.841835) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 16:31:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-02-25 16:31:36 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106303 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.961075) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:35.961430) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022554 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.011941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.012331) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3eaf4e1122e7 --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 3eaf4e1122e7 --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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 16:31:36,581 Setting DEBUG logging. 2016-02-25 16:31:36,581 attempt 1/10 2016-02-25 16:31:36,581 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3eaf4e1122e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 16:31:36,832 unpacking tar archive at: mozilla-beta-3eaf4e1122e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.590040 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.616070) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.616490) ========= echo 3eaf4e1122e7 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3eaf4e1122e7'] 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3eaf4e1122e7 program finished with exit code 0 elapsedTime=0.004644 script_repo_revision: '3eaf4e1122e7' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.667744) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.668079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 16:31:36.678215) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-25 16:31:36.678666) ========= /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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:31:37 INFO - MultiFileLogger online at 20160225 16:31:37 in /builds/slave/test-pgo 16:31:37 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 16:31:37 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:31:37 INFO - {'append_to_log': False, 16:31:37 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:31:37 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:31:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:31:37 INFO - 'branch': 'Mozilla-Beta', 16:31:37 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:37 INFO - 'config_files': ('talos/linux_config.py',), 16:31:37 INFO - 'default_actions': ('clobber', 16:31:37 INFO - 'read-buildbot-config', 16:31:37 INFO - 'download-and-extract', 16:31:37 INFO - 'populate-webroot', 16:31:37 INFO - 'create-virtualenv', 16:31:37 INFO - 'install', 16:31:37 INFO - 'run-tests'), 16:31:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:37 INFO - 'download_minidump_stackwalk': True, 16:31:37 INFO - 'download_symbols': 'ondemand', 16:31:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:37 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:37 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:37 INFO - 'installer_path': 'installer.exe', 16:31:37 INFO - 'log_level': 'info', 16:31:37 INFO - 'log_name': 'talos', 16:31:37 INFO - 'log_to_console': True, 16:31:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:31:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:31:37 INFO - 'opt_config_files': (), 16:31:37 INFO - 'pip_index': False, 16:31:37 INFO - 'sps_profile': False, 16:31:37 INFO - 'sps_profile_interval': 0, 16:31:37 INFO - 'suite': 'dromaeojs', 16:31:37 INFO - 'system_bits': '32', 16:31:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:37 INFO - 'title': 'talos-linux64-ix-026', 16:31:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:37 INFO - 'use_talos_json': True, 16:31:37 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:31:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:37 INFO - 'work_dir': 'build'} 16:31:37 INFO - ##### 16:31:37 INFO - ##### Running clobber step. 16:31:37 INFO - ##### 16:31:37 INFO - Running pre-action listener: _resource_record_pre_action 16:31:37 INFO - Running main action method: clobber 16:31:37 INFO - rmtree: /builds/slave/test-pgo/build 16:31:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:31:38 INFO - Running post-action listener: _resource_record_post_action 16:31:38 INFO - ##### 16:31:38 INFO - ##### Running read-buildbot-config step. 16:31:38 INFO - ##### 16:31:38 INFO - Running pre-action listener: _resource_record_pre_action 16:31:38 INFO - Running main action method: read_buildbot_config 16:31:38 INFO - Using buildbot properties: 16:31:38 INFO - { 16:31:38 INFO - "properties": { 16:31:38 INFO - "buildnumber": 123, 16:31:38 INFO - "product": "firefox", 16:31:38 INFO - "script_repo_revision": "production", 16:31:38 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:31:38 INFO - "repository": "", 16:31:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:31:38 INFO - "buildid": "20160225135328", 16:31:38 INFO - "slavename": "talos-linux64-ix-026", 16:31:38 INFO - "pgo_build": "True", 16:31:38 INFO - "basedir": "/builds/slave/test-pgo", 16:31:38 INFO - "project": "", 16:31:38 INFO - "platform": "ubuntu64_hw", 16:31:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:31:38 INFO - "slavebuilddir": "test", 16:31:38 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:31:38 INFO - "branch": "mozilla-beta", 16:31:38 INFO - "repo_path": "releases/mozilla-beta", 16:31:38 INFO - "stage_platform": "linux64-pgo", 16:31:38 INFO - "builduid": "9adc4a4967034374b69f1c24323b1ac8", 16:31:38 INFO - "revision": "3eaf4e1122e7" 16:31:38 INFO - }, 16:31:38 INFO - "sourcestamp": { 16:31:38 INFO - "repository": "", 16:31:38 INFO - "hasPatch": false, 16:31:38 INFO - "project": "", 16:31:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:31:38 INFO - "changes": [ 16:31:38 INFO - { 16:31:38 INFO - "category": null, 16:31:38 INFO - "files": [ 16:31:38 INFO - { 16:31:38 INFO - "url": null, 16:31:38 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2" 16:31:38 INFO - } 16:31:38 INFO - ], 16:31:38 INFO - "repository": "", 16:31:38 INFO - "rev": "3eaf4e1122e7", 16:31:38 INFO - "who": "sendchange", 16:31:38 INFO - "when": 1456446631, 16:31:38 INFO - "number": 7656354, 16:31:38 INFO - "comments": "Bug 1236578: Don_t display hidden add-ons in the select your add-ons UI. r=rhelmer a=sylvestre", 16:31:38 INFO - "project": "", 16:31:38 INFO - "at": "Thu 25 Feb 2016 16:30:31", 16:31:38 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:31:38 INFO - "revlink": "", 16:31:38 INFO - "properties": [ 16:31:38 INFO - [ 16:31:38 INFO - "buildid", 16:31:38 INFO - "20160225135328", 16:31:38 INFO - "Change" 16:31:38 INFO - ], 16:31:38 INFO - [ 16:31:38 INFO - "builduid", 16:31:38 INFO - "9adc4a4967034374b69f1c24323b1ac8", 16:31:38 INFO - "Change" 16:31:38 INFO - ], 16:31:38 INFO - [ 16:31:38 INFO - "pgo_build", 16:31:38 INFO - "True", 16:31:38 INFO - "Change" 16:31:38 INFO - ] 16:31:38 INFO - ], 16:31:38 INFO - "revision": "3eaf4e1122e7" 16:31:38 INFO - } 16:31:38 INFO - ], 16:31:38 INFO - "revision": "3eaf4e1122e7" 16:31:38 INFO - } 16:31:38 INFO - } 16:31:38 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2. 16:31:38 INFO - Running post-action listener: _resource_record_post_action 16:31:38 INFO - ##### 16:31:38 INFO - ##### Running download-and-extract step. 16:31:38 INFO - ##### 16:31:38 INFO - Running pre-action listener: _resource_record_pre_action 16:31:38 INFO - Running main action method: download_and_extract 16:31:38 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:31: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')]} 16:31:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/test_packages.json 16:31:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:31:38 INFO - Downloaded 1270 bytes. 16:31:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:31:38 INFO - Using the following test package requirements: 16:31:38 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:31:38 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:31:38 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'jsshell-linux-x86_64.zip'], 16:31:38 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:31:38 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:31:38 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 16:31:38 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 16:31:38 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:31:38 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:31:38 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:31:38 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:31:38 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:31: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')]} 16:31:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:31:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:38 INFO - Downloaded 21584308 bytes. 16:31: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 16:31:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:31: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 16:31:39 INFO - Return code: 0 16:31:39 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 16:31:39 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')]} 16:31:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:31:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:40 INFO - Downloaded 21584308 bytes. 16:31:40 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 16:31:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:31:40 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 16:31:41 INFO - Return code: 0 16:31:41 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')]} 16:31:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:31:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:41 INFO - Downloaded 11132038 bytes. 16:31:41 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 16:31:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:31:41 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 16:31:42 INFO - Return code: 0 16:31:42 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')]} 16:31:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:31:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/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 16:31:42 INFO - Downloaded 57940666 bytes. 16:31:42 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:31:42 INFO - mkdir: /builds/slave/test-pgo/properties 16:31:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:31:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:31:42 INFO - Contents: 16:31:42 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:31:43 INFO - Running post-action listener: _resource_record_post_action 16:31:43 INFO - Running post-action listener: set_extra_try_arguments 16:31:43 INFO - ##### 16:31:43 INFO - ##### Running populate-webroot step. 16:31:43 INFO - ##### 16:31:43 INFO - Running pre-action listener: _resource_record_pre_action 16:31:43 INFO - Running main action method: populate_webroot 16:31:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:43 INFO - '--tppagecycles', 16:31:43 INFO - '7'], 16:31:43 INFO - 'tests': ['tsvgm']}}, 16:31:43 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:31:43 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:43 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:31:43 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:31:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'tests': ['damp', 'tps']}, 16:31:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['damp', 'tps']}, 16:31:43 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:31:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:43 INFO - 'other': {'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'other_l64': {'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'other_nol64': {'tests': ['a11yr', 16:31:43 INFO - 'ts_paint', 16:31:43 INFO - 'tpaint', 16:31:43 INFO - 'sessionrestore', 16:31:43 INFO - 'sessionrestore_no_auto_restore']}, 16:31:43 INFO - 'svgr': {'tests': ['tsvgx', 16:31:43 INFO - 'tsvgr_opacity', 16:31:43 INFO - 'tart', 16:31:43 INFO - 'tscrollx', 16:31:43 INFO - 'cart']}, 16:31:43 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['tsvgx', 16:31:43 INFO - 'tsvgr_opacity', 16:31:43 INFO - 'tart', 16:31:43 INFO - 'tscrollx', 16:31:43 INFO - 'cart']}, 16:31:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'tests': ['tp5o']}, 16:31:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'talos_options': ['--e10s'], 16:31:43 INFO - 'tests': ['tp5o']}, 16:31:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'talos_options': ['--xperf_path', 16:31:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:43 INFO - 'tests': ['tp5n']}, 16:31:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:43 INFO - 'talos_options': ['--e10s', 16:31:43 INFO - '--xperf_path', 16:31:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:43 INFO - 'tests': ['tp5n']}}, 16:31:43 INFO - 'talos.zip': {'path': '', 16:31:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:43 INFO - Running post-action listener: _resource_record_post_action 16:31:43 INFO - ##### 16:31:43 INFO - ##### Running create-virtualenv step. 16:31:43 INFO - ##### 16:31:43 INFO - Running pre-action listener: _resource_record_pre_action 16:31:43 INFO - Running main action method: create_virtualenv 16:31:43 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:31:43 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 16:31:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:31:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:43 INFO - Using real prefix '/usr' 16:31:43 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:31:43 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:45 INFO - Installing pip.................done. 16:31:45 INFO - Return code: 0 16:31:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:31:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:31:45 INFO - Using env: {'DISPLAY': ':0', 16:31:45 INFO - 'HOME': '/home/cltbld', 16:31:45 INFO - 'LANG': 'en_US.UTF-8', 16:31:45 INFO - 'LANGUAGE': 'en_US:en', 16:31:45 INFO - 'LOGNAME': 'cltbld', 16:31:45 INFO - 'MAIL': '/var/mail/cltbld', 16:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:45 INFO - 'MOZ_NO_REMOTE': '1', 16:31:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:45 INFO - 'NO_EM_RESTART': '1', 16:31:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:45 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:45 INFO - 'SHELL': '/bin/bash', 16:31:45 INFO - 'SHLVL': '1', 16:31:45 INFO - 'TERM': 'linux', 16:31:45 INFO - 'TMOUT': '86400', 16:31:45 INFO - 'USER': 'cltbld', 16:31:45 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:45 INFO - '_': '/tools/buildbot/bin/python'} 16:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:31:45 INFO - Cleaning up... 16:31:45 INFO - Return code: 0 16:31:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:31:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:31:45 INFO - Using env: {'DISPLAY': ':0', 16:31:45 INFO - 'HOME': '/home/cltbld', 16:31:45 INFO - 'LANG': 'en_US.UTF-8', 16:31:45 INFO - 'LANGUAGE': 'en_US:en', 16:31:45 INFO - 'LOGNAME': 'cltbld', 16:31:45 INFO - 'MAIL': '/var/mail/cltbld', 16:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:45 INFO - 'MOZ_NO_REMOTE': '1', 16:31:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:45 INFO - 'NO_EM_RESTART': '1', 16:31:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:45 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:45 INFO - 'SHELL': '/bin/bash', 16:31:45 INFO - 'SHLVL': '1', 16:31:45 INFO - 'TERM': 'linux', 16:31:45 INFO - 'TMOUT': '86400', 16:31:45 INFO - 'USER': 'cltbld', 16:31:45 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:45 INFO - '_': '/tools/buildbot/bin/python'} 16:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:46 INFO - Downloading/unpacking psutil>=0.7.1 16:31:46 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 16:31:46 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 16:31:46 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 16:31:46 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 16:31:46 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 16:31:46 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 16:31:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:48 INFO - Installing collected packages: psutil 16:31:48 INFO - Running setup.py install for psutil 16:31:48 INFO - building 'psutil._psutil_linux' extension 16:31:48 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 16:31:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:31:48 INFO - building 'psutil._psutil_posix' extension 16:31:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:31:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:31:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:48 INFO - Successfully installed psutil 16:31:48 INFO - Cleaning up... 16:31:48 INFO - Return code: 0 16:31:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:31:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:31:48 INFO - Using env: {'DISPLAY': ':0', 16:31:48 INFO - 'HOME': '/home/cltbld', 16:31:48 INFO - 'LANG': 'en_US.UTF-8', 16:31:48 INFO - 'LANGUAGE': 'en_US:en', 16:31:48 INFO - 'LOGNAME': 'cltbld', 16:31:48 INFO - 'MAIL': '/var/mail/cltbld', 16:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:48 INFO - 'MOZ_NO_REMOTE': '1', 16:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:48 INFO - 'NO_EM_RESTART': '1', 16:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:48 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:48 INFO - 'SHELL': '/bin/bash', 16:31:48 INFO - 'SHLVL': '1', 16:31:48 INFO - 'TERM': 'linux', 16:31:48 INFO - 'TMOUT': '86400', 16:31:48 INFO - 'USER': 'cltbld', 16:31:48 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:48 INFO - '_': '/tools/buildbot/bin/python'} 16:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:31:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:31:50 INFO - Installing collected packages: mozsystemmonitor 16:31:50 INFO - Running setup.py install for mozsystemmonitor 16:31:50 INFO - Successfully installed mozsystemmonitor 16:31:50 INFO - Cleaning up... 16:31:50 INFO - Return code: 0 16:31:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:31:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:31:50 INFO - Using env: {'DISPLAY': ':0', 16:31:50 INFO - 'HOME': '/home/cltbld', 16:31:50 INFO - 'LANG': 'en_US.UTF-8', 16:31:50 INFO - 'LANGUAGE': 'en_US:en', 16:31:50 INFO - 'LOGNAME': 'cltbld', 16:31:50 INFO - 'MAIL': '/var/mail/cltbld', 16:31:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:50 INFO - 'MOZ_NO_REMOTE': '1', 16:31:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:50 INFO - 'NO_EM_RESTART': '1', 16:31:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:50 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:50 INFO - 'SHELL': '/bin/bash', 16:31:50 INFO - 'SHLVL': '1', 16:31:50 INFO - 'TERM': 'linux', 16:31:50 INFO - 'TMOUT': '86400', 16:31:50 INFO - 'USER': 'cltbld', 16:31:50 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:50 INFO - '_': '/tools/buildbot/bin/python'} 16:31:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:51 INFO - Downloading/unpacking blobuploader==1.2.4 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:52 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:31:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:53 INFO - Downloading docopt-0.6.1.tar.gz 16:31:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:53 INFO - Running setup.py install for blobuploader 16:31:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:31:53 INFO - Running setup.py install for requests 16:31:54 INFO - Running setup.py install for docopt 16:31:54 INFO - Successfully installed blobuploader requests docopt 16:31:54 INFO - Cleaning up... 16:31:54 INFO - Return code: 0 16:31:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:31:54 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')]} 16:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:54 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')]} 16:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:54 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 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:54 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 16:31:54 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 16:31:54 INFO - Using env: {'DISPLAY': ':0', 16:31:54 INFO - 'HOME': '/home/cltbld', 16:31:54 INFO - 'LANG': 'en_US.UTF-8', 16:31:54 INFO - 'LANGUAGE': 'en_US:en', 16:31:54 INFO - 'LOGNAME': 'cltbld', 16:31:54 INFO - 'MAIL': '/var/mail/cltbld', 16:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:54 INFO - 'MOZ_NO_REMOTE': '1', 16:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:54 INFO - 'NO_EM_RESTART': '1', 16:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:54 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:54 INFO - 'SHELL': '/bin/bash', 16:31:54 INFO - 'SHLVL': '1', 16:31:54 INFO - 'TERM': 'linux', 16:31:54 INFO - 'TMOUT': '86400', 16:31:54 INFO - 'USER': 'cltbld', 16:31:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:54 INFO - '_': '/tools/buildbot/bin/python'} 16:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:31:54 INFO - Running setup.py (path:/tmp/pip-Kc1FF6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:31:54 INFO - Running setup.py (path:/tmp/pip-6orKqu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:31:54 INFO - Running setup.py (path:/tmp/pip-qKajJn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:31:54 INFO - Running setup.py (path:/tmp/pip-pter6Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:31:54 INFO - Running setup.py (path:/tmp/pip-hYzVjq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:31:54 INFO - Running setup.py (path:/tmp/pip-5GUlEq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:31:54 INFO - Running setup.py (path:/tmp/pip-ovvsWN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:31:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:31:54 INFO - Running setup.py (path:/tmp/pip-vK0Osz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:31:55 INFO - Running setup.py (path:/tmp/pip-Fy8wvV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:31:55 INFO - Running setup.py (path:/tmp/pip-cSjnQo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:31:55 INFO - Running setup.py (path:/tmp/pip-0t2tQf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:31:55 INFO - Running setup.py (path:/tmp/pip-tcNaP1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:31:55 INFO - Running setup.py (path:/tmp/pip-FcboYi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:31:55 INFO - Running setup.py (path:/tmp/pip-DlrWFR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:31:55 INFO - Running setup.py (path:/tmp/pip-eJcJBo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:31:55 INFO - Running setup.py (path:/tmp/pip-0Vqoi4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:31:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:31:55 INFO - Running setup.py (path:/tmp/pip-43EYWT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:31:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:31:55 INFO - Running setup.py install for manifestparser 16:31:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:31:55 INFO - Running setup.py install for mozcrash 16:31:55 INFO - Running setup.py install for mozdebug 16:31:55 INFO - Running setup.py install for mozdevice 16:31:56 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for mozfile 16:31:56 INFO - Running setup.py install for mozhttpd 16:31:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for mozinfo 16:31:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for mozInstall 16:31:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for mozleak 16:31:56 INFO - Running setup.py install for mozlog 16:31:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for moznetwork 16:31:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:31:56 INFO - Running setup.py install for mozprocess 16:31:56 INFO - Running setup.py install for mozprofile 16:31:57 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:31:57 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:31:57 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:31:57 INFO - Running setup.py install for mozrunner 16:31:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:31:57 INFO - Running setup.py install for mozscreenshot 16:31:57 INFO - Running setup.py install for moztest 16:31:57 INFO - Running setup.py install for mozversion 16:31:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:31:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:57 INFO - Cleaning up... 16:31:57 INFO - Return code: 0 16:31:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:31:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:31:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:31:57 INFO - Using env: {'DISPLAY': ':0', 16:31:57 INFO - 'HOME': '/home/cltbld', 16:31:57 INFO - 'LANG': 'en_US.UTF-8', 16:31:57 INFO - 'LANGUAGE': 'en_US:en', 16:31:57 INFO - 'LOGNAME': 'cltbld', 16:31:57 INFO - 'MAIL': '/var/mail/cltbld', 16:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:57 INFO - 'MOZ_NO_REMOTE': '1', 16:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:57 INFO - 'NO_EM_RESTART': '1', 16:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:31:57 INFO - 'PWD': '/builds/slave/test-pgo', 16:31:57 INFO - 'SHELL': '/bin/bash', 16:31:57 INFO - 'SHLVL': '1', 16:31:57 INFO - 'TERM': 'linux', 16:31:57 INFO - 'TMOUT': '86400', 16:31:57 INFO - 'USER': 'cltbld', 16:31:57 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:57 INFO - '_': '/tools/buildbot/bin/python'} 16:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:31:57 INFO - Running setup.py (path:/tmp/pip-5OC7C7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 16:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:31:57 INFO - Running setup.py (path:/tmp/pip-bdgzsd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:31:57 INFO - Running setup.py (path:/tmp/pip-zPf8yZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:31:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 16:31:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:31:57 INFO - Running setup.py (path:/tmp/pip-q3704t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:31:58 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)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:31:58 INFO - Running setup.py (path:/tmp/pip-pysW8P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:31:58 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)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:31:58 INFO - Running setup.py (path:/tmp/pip-3x2j_F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:31:58 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)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:31:58 INFO - Running setup.py (path:/tmp/pip-IubKgr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:31:58 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)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:31:58 INFO - Running setup.py (path:/tmp/pip-DtHCu8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:31:58 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)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:31:58 INFO - Running setup.py (path:/tmp/pip-b7bvVw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:31:58 INFO - Running setup.py (path:/tmp/pip-mKqv9E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:31:58 INFO - Running setup.py (path:/tmp/pip-HLV4pV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:31:58 INFO - Running setup.py (path:/tmp/pip-0KG6KJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:31:58 INFO - Running setup.py (path:/tmp/pip-TTjP7U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:31:58 INFO - Running setup.py (path:/tmp/pip-UhJI5x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:31:58 INFO - Running setup.py (path:/tmp/pip-nzfGyE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:31:58 INFO - Running setup.py (path:/tmp/pip-VPKFyN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 16:31:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:31:58 INFO - Running setup.py (path:/tmp/pip-g2cDme-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:00 INFO - Downloading blessings-1.6.tar.gz 16:32:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:00 INFO - Installing collected packages: blessings 16:32:00 INFO - Running setup.py install for blessings 16:32:00 INFO - Successfully installed blessings 16:32:00 INFO - Cleaning up... 16:32:00 INFO - Return code: 0 16:32:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:32:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:32:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:32:01 INFO - Reading from file tmpfile_stdout 16:32:01 INFO - Current package versions: 16:32:01 INFO - argparse == 1.2.1 16:32:01 INFO - blessings == 1.6 16:32:01 INFO - blobuploader == 1.2.4 16:32:01 INFO - docopt == 0.6.1 16:32:01 INFO - manifestparser == 1.1 16:32:01 INFO - mozInstall == 1.12 16:32:01 INFO - mozcrash == 0.16 16:32:01 INFO - mozdebug == 0.1 16:32:01 INFO - mozdevice == 0.47 16:32:01 INFO - mozfile == 1.2 16:32:01 INFO - mozhttpd == 0.7 16:32:01 INFO - mozinfo == 0.9 16:32:01 INFO - mozleak == 0.1 16:32:01 INFO - mozlog == 3.1 16:32:01 INFO - moznetwork == 0.27 16:32:01 INFO - mozprocess == 0.22 16:32:01 INFO - mozprofile == 0.27 16:32:01 INFO - mozrunner == 6.11 16:32:01 INFO - mozscreenshot == 0.1 16:32:01 INFO - mozsystemmonitor == 0.0 16:32:01 INFO - moztest == 0.7 16:32:01 INFO - mozversion == 1.4 16:32:01 INFO - psutil == 3.1.1 16:32:01 INFO - requests == 1.2.3 16:32:01 INFO - wsgiref == 0.1.2 16:32:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:01 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 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:32:01 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 16:32:01 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 16:32:01 INFO - Using env: {'DISPLAY': ':0', 16:32:01 INFO - 'HOME': '/home/cltbld', 16:32:01 INFO - 'LANG': 'en_US.UTF-8', 16:32:01 INFO - 'LANGUAGE': 'en_US:en', 16:32:01 INFO - 'LOGNAME': 'cltbld', 16:32:01 INFO - 'MAIL': '/var/mail/cltbld', 16:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:01 INFO - 'MOZ_NO_REMOTE': '1', 16:32:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:01 INFO - 'NO_EM_RESTART': '1', 16:32:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:32:01 INFO - 'PWD': '/builds/slave/test-pgo', 16:32:01 INFO - 'SHELL': '/bin/bash', 16:32:01 INFO - 'SHLVL': '1', 16:32:01 INFO - 'TERM': 'linux', 16:32:01 INFO - 'TMOUT': '86400', 16:32:01 INFO - 'USER': 'cltbld', 16:32:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:01 INFO - '_': '/tools/buildbot/bin/python'} 16:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 16:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:32:01 INFO - Cleaning up... 16:32:01 INFO - Return code: 0 16:32:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:32:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:32:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:32:01 INFO - Using env: {'DISPLAY': ':0', 16:32:01 INFO - 'HOME': '/home/cltbld', 16:32:01 INFO - 'LANG': 'en_US.UTF-8', 16:32:01 INFO - 'LANGUAGE': 'en_US:en', 16:32:01 INFO - 'LOGNAME': 'cltbld', 16:32:01 INFO - 'MAIL': '/var/mail/cltbld', 16:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:01 INFO - 'MOZ_NO_REMOTE': '1', 16:32:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:01 INFO - 'NO_EM_RESTART': '1', 16:32:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:32:01 INFO - 'PWD': '/builds/slave/test-pgo', 16:32:01 INFO - 'SHELL': '/bin/bash', 16:32:01 INFO - 'SHLVL': '1', 16:32:01 INFO - 'TERM': 'linux', 16:32:01 INFO - 'TMOUT': '86400', 16:32:01 INFO - 'USER': 'cltbld', 16:32:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:01 INFO - '_': '/tools/buildbot/bin/python'} 16:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:01 INFO - Downloading/unpacking jsonschema 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:03 INFO - Downloading/unpacking functools32 (from jsonschema) 16:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:03 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:03 INFO - warning: no files found matching '*.txt' 16:32:03 INFO - no previously-included directories found matching 'build' 16:32:03 INFO - no previously-included directories found matching 'dist' 16:32:03 INFO - no previously-included directories found matching '.git*' 16:32:03 INFO - Installing collected packages: jsonschema, functools32 16:32:03 INFO - Running setup.py install for functools32 16:32:03 INFO - warning: no files found matching '*.txt' 16:32:03 INFO - no previously-included directories found matching 'build' 16:32:03 INFO - no previously-included directories found matching 'dist' 16:32:03 INFO - no previously-included directories found matching '.git*' 16:32:03 INFO - Successfully installed jsonschema functools32 16:32:03 INFO - Cleaning up... 16:32:03 INFO - Return code: 0 16:32:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6c6f105f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, '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': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', '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 16:32:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 16:32:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:32:03 INFO - Using env: {'DISPLAY': ':0', 16:32:03 INFO - 'HOME': '/home/cltbld', 16:32:03 INFO - 'LANG': 'en_US.UTF-8', 16:32:03 INFO - 'LANGUAGE': 'en_US:en', 16:32:03 INFO - 'LOGNAME': 'cltbld', 16:32:03 INFO - 'MAIL': '/var/mail/cltbld', 16:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:03 INFO - 'MOZ_NO_REMOTE': '1', 16:32:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:03 INFO - 'NO_EM_RESTART': '1', 16:32:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:32:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:32:03 INFO - 'SHELL': '/bin/bash', 16:32:03 INFO - 'SHLVL': '1', 16:32:03 INFO - 'TERM': 'linux', 16:32:03 INFO - 'TMOUT': '86400', 16:32:03 INFO - 'USER': 'cltbld', 16:32:03 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:03 INFO - '_': '/tools/buildbot/bin/python'} 16:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:04 INFO - Downloading/unpacking flake8 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:32:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:32:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:32:06 INFO - no previously-included directories found matching 'docs/_build' 16:32:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:32:06 INFO - Running setup.py install for pep8 16:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:32:06 INFO - no previously-included directories found matching 'docs/_build' 16:32:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:32:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:32:06 INFO - Cleaning up... 16:32:06 INFO - Return code: 0 16:32:06 INFO - Running post-action listener: _resource_record_post_action 16:32:06 INFO - Running post-action listener: _start_resource_monitoring 16:32:06 INFO - Starting resource monitoring. 16:32:06 INFO - ##### 16:32:06 INFO - ##### Running install step. 16:32:06 INFO - ##### 16:32:06 INFO - Running pre-action listener: _resource_record_pre_action 16:32:06 INFO - Running main action method: install 16:32:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:32:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:32:07 INFO - Reading from file tmpfile_stdout 16:32:07 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:32:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:32:07 INFO - Reading from file tmpfile_stdout 16:32:07 INFO - Output received: 16:32:07 INFO - Usage: mozinstall [options] installer 16:32:07 INFO - Options: 16:32:07 INFO - -h, --help show this help message and exit 16:32:07 INFO - -d DEST, --destination=DEST 16:32:07 INFO - Directory to install application into. [default: 16:32:07 INFO - "/builds/slave/test-pgo"] 16:32:07 INFO - --app=APP Application being installed. [default: firefox] 16:32:07 INFO - mkdir: /builds/slave/test-pgo/build/application 16:32:07 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'] 16:32:07 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 16:32:21 INFO - Reading from file tmpfile_stdout 16:32:21 INFO - Output received: 16:32:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:32:21 INFO - Running post-action listener: _resource_record_post_action 16:32:21 INFO - ##### 16:32:21 INFO - ##### Running run-tests step. 16:32:21 INFO - ##### 16:32:21 INFO - Running pre-action listener: _resource_record_pre_action 16:32:21 INFO - Running main action method: run_tests 16:32:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:32:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:32:21 INFO - Python 2.7.3 16:32:21 INFO - Return code: 0 16:32:21 INFO - grabbing minidump binary from tooltool 16:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2363350>, '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 0x2364250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23646e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:32:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 16:32:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:32:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:21 INFO - Return code: 0 16:32:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:32:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:32:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:32:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:32:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:32:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:32:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:32:22 INFO - Return code: 0 16:32:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:32:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --webServer localhost 16:32:22 INFO - Using env: {'DISPLAY': ':0', 16:32:22 INFO - 'HOME': '/home/cltbld', 16:32:22 INFO - 'LANG': 'en_US.UTF-8', 16:32:22 INFO - 'LANGUAGE': 'en_US:en', 16:32:22 INFO - 'LOGNAME': 'cltbld', 16:32:22 INFO - 'MAIL': '/var/mail/cltbld', 16:32:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:32:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:22 INFO - 'MOZ_NO_REMOTE': '1', 16:32:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:32:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:22 INFO - 'NO_EM_RESTART': '1', 16:32:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:32:22 INFO - 'PWD': '/builds/slave/test-pgo', 16:32:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:32:22 INFO - 'SHELL': '/bin/bash', 16:32:22 INFO - 'SHLVL': '1', 16:32:22 INFO - 'TERM': 'linux', 16:32:22 INFO - 'TMOUT': '86400', 16:32:22 INFO - 'USER': 'cltbld', 16:32:22 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026', 16:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:22 INFO - '_': '/tools/buildbot/bin/python'} 16:32:22 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost'] with output_timeout 3600 16:32:23 INFO - mozversion INFO | application_buildid: 20160225135328 16:32:23 INFO - mozversion INFO | application_changeset: 3eaf4e1122e7c75fc2215034d8229c082b76cf19 16:32:23 INFO - mozversion INFO | application_display_name: Nightly 16:32:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:23 INFO - mozversion INFO | application_name: Firefox 16:32:23 INFO - mozversion INFO | application_remotingname: firefox 16:32:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:32:23 INFO - mozversion INFO | application_vendor: Mozilla 16:32:23 INFO - mozversion INFO | application_version: 45.0 16:32:23 INFO - mozversion INFO | platform_buildid: 20160225135328 16:32:23 INFO - mozversion INFO | platform_changeset: 3eaf4e1122e7c75fc2215034d8229c082b76cf19 16:32:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:32:23 INFO - mozversion INFO | platform_version: 45.0 16:32:23 INFO - 2016-02-25 16:32:23,155 DEBUG : using testdate: 1456446743 16:32:23 INFO - 2016-02-25 16:32:23,155 DEBUG : actual date: 1456446743 16:32:23 INFO - 2016-02-25 16:32:23,163 INFO : starting webserver on 'localhost:57086' 16:32:23 INFO - 2016-02-25 16:32:23,164 INFO : Starting test suite talos-linux64-ix-026 16:32:23 INFO - 2016-02-25 16:32:23,164 INFO : Starting test dromaeo_css 16:32:23 INFO - 2016-02-25 16:32:23,164 DEBUG : operating with platform_type : linux_ 16:32:23 INFO - 2016-02-25 16:32:23,165 INFO : Initialising browser for dromaeo_css test... 16:32:23 INFO - 2016-02-25 16:32:23,179 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpR5gsAe/profile http://localhost:57086/getInfo.html 16:32:27 INFO - 2016-02-25 16:32:27,022 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:32:27 INFO - 2016-02-25 16:32:27,022 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:32:27 INFO - 2016-02-25 16:32:27,022 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:32:27 INFO - 2016-02-25 16:32:27,023 DEBUG : BROWSER_OUTPUT: __metrics 16:32:27 INFO - 2016-02-25 16:32:27,164 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] 16:32:27 INFO - 2016-02-25 16:32:27,929 INFO : Browser initialized. 16:32:27 INFO - 2016-02-25 16:32:27,930 INFO : Running cycle 1/1 for dromaeo_css test... 16:32:27 INFO - 2016-02-25 16:32:27,930 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpR5gsAe/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:32:39 INFO - 2016-02-25 16:32:39,580 DEBUG : BROWSER_OUTPUT: RSS: Main: 134438912 16:32:39 INFO - 2016-02-25 16:32:39,580 DEBUG : BROWSER_OUTPUT: 16:33:30 INFO - 2016-02-25 16:33:30,527 DEBUG : BROWSER_OUTPUT: 1456446810523 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] 16:35:38 INFO - 2016-02-25 16:35:38,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57086/tests/dromaeo/cssquery-ext.html) 16:35:39 INFO - 2016-02-25 16:35:39,673 DEBUG : BROWSER_OUTPUT: RSS: Main: 233766912 16:35:39 INFO - 2016-02-25 16:35:39,673 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2016-02-25 16:38:41,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-ext.html (next: http://localhost:57086/tests/dromaeo/cssquery-jquery.html) 16:38:42 INFO - 2016-02-25 16:38:42,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 210624512 16:38:42 INFO - 2016-02-25 16:38:42,203 DEBUG : BROWSER_OUTPUT: 16:41:44 INFO - 2016-02-25 16:41:44,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57086/tests/dromaeo/cssquery-mootools.html) 16:41:44 INFO - 2016-02-25 16:41:44,589 DEBUG : BROWSER_OUTPUT: RSS: Main: 209522688 16:41:44 INFO - 2016-02-25 16:41:44,590 DEBUG : BROWSER_OUTPUT: 16:44:46 INFO - 2016-02-25 16:44:46,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57086/tests/dromaeo/cssquery-prototype.html) 16:44:46 INFO - 2016-02-25 16:44:46,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 195747840 16:44:46 INFO - 2016-02-25 16:44:46,842 DEBUG : BROWSER_OUTPUT: 16:47:49 INFO - 2016-02-25 16:47:49,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57086/tests/dromaeo/cssquery-yui.html) 16:47:49 INFO - 2016-02-25 16:47:49,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 196001792 16:47:49 INFO - 2016-02-25 16:47:49,858 DEBUG : BROWSER_OUTPUT: 16:50:51 INFO - 2016-02-25 16:50:51,953 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/dromaeo/cssquery-yui.html (next: http://localhost:57086/tests/dromaeo/cssquery-dojo.html) 16:50:52 INFO - 2016-02-25 16:50:52,211 DEBUG : BROWSER_OUTPUT: RSS: Main: 239849472 16:50:52 INFO - 2016-02-25 16:50:52,211 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,212 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:50:52 INFO - 2016-02-25 16:50:52,212 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:50:52 INFO - 2016-02-25 16:50:52,212 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:50:52 INFO - 2016-02-25 16:50:52,212 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:50:52 INFO - 2016-02-25 16:50:52,213 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3278.4754439289713;3367.4780409036575;3390.4914262860584;3390.9830450847744;3423.6405177456368;5584.9720751396235;5596.048671725806;5596.160575913615;5600.6079574429805;5620.747066382018;5610.775568977247;5611.915821262684;5621.7751289948455;5622.269105016352;5628.802991895284;4947.901041979158;5093.694378337303;5109.897802043957;5166.147585648364;5219.86950326243;5044.2937988681615;5080.187170052793;5148.922766158511;5149.304843846089;5161.483851614846;5154.149565321717;5158.277841102249;5158.587313014949;5167.534921857032;5193.974030129844;4974.129527332739;5144.922826157611;5156.458571849977;5214;5232.345956755386;4980.651354405193;5056.924146137811;5074.695518268915;5087.720175390389;5128.282040514331;5412.242286079952;5442.564594832404;5443.55139721803;5448.482394172547;5451.291332126798;5798.82603521895;5904.0258357370985;6094.96952515239;6101.267847858241;6103.23709536308;6065.2418447694035;6130.938690613126;6142.447179753844;6152.907706384408;6157.815265542041;5790.305163380422;5794.62334948227;5800.738966746507;5812.360640329561;5821.476067153934;5994.400559943971;6057.303410107806;6088.878222435526;6186.876262474815;6279.340669229757;6097.774312375951;6099.451049405575;6112.663803490762;6128.050152226412;6129.08063790435;6131.295214939155;6185.907211391833;6187.690615469209;6188.412100850412;6203.627782333075;5847.239858818326;5875.823725288248;5967;6044.304904935989;6120.990036643993;6016.488598469092;6056.182415373956;6076.605020673642;6082.543809214327;6094;4646.233371493733;4650.697704649187;4655.534446555385;4655.720656760605;4694.81220751173;4037.212743515045;4069.816858241327;4102.876913692594;4155.916881662411;4159.708820382604;5630.0991841305195;5662.6036177468;5663.263775709132;5665.291838520185;5831.533477321804;6070.0591408331775;6117.541184411187;6121.908171377433;6122.846928826815;6127.234095738033;5566.972165139229;5644.57665675076;5671.801486947937;5757.337906140764;5759.2512973313205;5258.474152584711;5263.631545791758;5264.842054738364;5277.050130976461;5288.312519372457;6207.193064901534;6212.161358216583;6215.782447614311;6216.129741836146;6217.533684973645;5972.432618901197;5989.981703110395;6084.11780291864;6093.025115981422;6108.72510737009;6200.348963358782;6202.224721909762;6207.6585787782105;6207.96896015517;6338.683065846782;6046.516278697605;6085.1480792689945;6098.237720284965;6099.268087829489;6104.267487901303;5973.253343332083;5992;6094.634321940698;6152.815415537541;6185.195924529782;5965.194698715618;5968.701564921824;5970.223870896929;5978.312494063239;5990.850228744316;5950.910736338872;5963;5963.165156878127;5963.9105413417965;5964.731587078679;5665.178549110438;5669.149627555735;5731.627444216113;5773.191753154646;5822.097574875901;5930.851728706817;5931.13998470228;5958.582899197015;5994.880102398015;6042.969785151046;5642.097264437834;5886.735096920735;5891.9116213255975;5900.852478688067;5901.704914754159 16:50:52 INFO - 2016-02-25 16:50:52,213 DEBUG : BROWSER_OUTPUT: |1;ext.html;576.2997957481659;615.0374663651386;624.1106423346731;634.7016902056032;692.6882902693789;33961.32077358451;43629.78185109074;43640.34539481902;43675;43681.781591092;47861.521384786145;47998;48479.51520484794;48560.51439485604;48628.51371486284;7863.449558530905;7950.602469876512;9162.862557061633;9292.163705266525;10567.73580660482;10865.402402867878;10936.289141205836;10966.835497467544;10970.561177552889;11000.889991100066;10899.182561307895;10928.890711092907;10985.121190304757;10989.230753847234;11039.06167975719;10820.513076911559;10901.236913416064;10923.726906827353;10965.451727413678;10980.725481862937;10765.51555180011;10814.945925270324;10867.402292873889;10889.618863339745;10893.128549716004;43465.130697386194;43833.56166438359;43855.561444385465;43884.561154388684;43938.34092488583;3326.8835590754206;3460.463628137643;3718.163413232001;3873.1285460771383;4179.832806687728;32638.6736132638;32939.341213175605;33189.50215746766;33275.83362083198;33309.83345083283;4316.848910288125;4354.782260886943;4356.76037817923;4366.192254432908;4390.802413891351;288449;290223;290354;290447;290639.54680226673;62908.68545657334;64768.676156618916;64783.67608162023;65044;65643.67178164174;70106.64946675336;70915.64542177255;71100.28899710937;71301;71340;17353.392631257844;17511.912440437718;17633.294668213384;17636.470905872844;17714.822851771576;13146;13262.137961032502;13386;13491.730165396637;13607.795883061763;20741.79258207429;21513.354599362043;21546.892265538572;21668;21697;7811.140774514799;8124.038442326435;8130.959345203236;8159.102498725136;8210.220029097227;988.8862780780158;1044.070777008463;1064.4730858225073;1088.5754555723274;1138.3909608359488;30503.389932201677;30656.23359415988;30664.23339416488;31139.221519462193;31302.530462043087;1092.5848177692585;1109.5839060352369;1113.1039513191797;1127.2053202492223;1234.2779474007807;14197.574072777834;16097.919510402608;16273.918630406772;16466.447700606426;16515.752263716055;340325;344185;346170;347064;347501;56801.14798278029;56983.71508142433;56990.43009569934;57064.42935570674;57109;1311.1805121798875;1394.6234516680643;1431.119861285323;1449.0943160524882;1462.700146469971;27450.176494705192;27790.44419111647;27810.16569502918;27919.302017449725;28771.856140719163;42327.3650895243;42444.57555424406;42706.78646606747;42719.14561708686;42725.786371067945;227933;233180;233434;234989;236184;33922.83038584791;34045.48931765976;34383.82808086044;34820;34837.65162348445;44936.32595511005;45453.318200226364;45789.771051145864;46390.30414543717;46527.53472465232;37353.6264637361;37786.811065944494;37834.243315134096;37915.43126853044;39120.413193802684;38612;38704;39318.2136357277;39809.60190398059;40286.39570406505;35715.10712232215;36139;38623.613763862;39074;39101.217975640895;30035;31060.68939310578;31712;31715.84142079275;32318.35363292768;25922.19250269493;26067.478650426507;26532.734672653027;26640.600390993764;26973.3256668577 16:50:52 INFO - 2016-02-25 16:50:52,213 DEBUG : BROWSER_OUTPUT: |2;jquery.html;387.41306920016183;387.4246743585775;388.06089263981164;391.68860755699217;392.4211787613269;15031.735435079354;16121.758173627404;17160;18404.079796010195;18432.539186520342;18386.264549418007;18411.90794046031;18676.159572819222;18680;18822.341218057372;6151.077338399231;6306.842328941791;6312.400321969406;6331.366863313678;6348.682565871711;4615.584597386234;4739.241721324589;6226;6769.966150169243;6988.196357418911;5441.455854414547;6901.171859376882;6926.168859736838;6985.05701730265;6991.056207411985;5541.224228607978;5545.168224766277;5548.0845660465975;5563.916541251884;5594.049011668006;5174.4825517448335;5177.508136727005;5180.585553155738;5182.792688292464;5229.320188375525;13564;13802.861971380258;16766.32934682611;21831.01760420785;21855.781442185693;2258.8644681319174;2263.2757517594378;2292.1862738727677;2322.8722420266877;2360.1503458754833;9715.854262186072;10131.949340253252;10567.10179634732;11386.373749443777;13960.650983725387;1980.861339706221;1980.9405717828488;2002.8698134621202;2011.1452632631074;2051.671732522804;66063;68449;68560.3143968564;70507;70517.64741176313;10746.516406761715;10869.891301087046;10905.127589792797;11084.612038578773;11184.94407527957;14372.640683982983;14494.202818844853;14500.347484363228;14507.1295722257;14513.564593062225;8320.417570769989;8424;8506.829863402698;8557.700480483278;8574.699885503978;8359.373047021498;8366.53984030872;8385.706500272583;8417.747467575982;8423.610104332716;4137.13120244752;4504.549545045469;4520.728756274634;4620.561046700558;4702.153612349742;1706.2744026418566;1903.448000079989;1929.2958070304237;1930.420873737873;1943.5044063763933;475.7882742179697;486.44302273896193;913.4975763330142;1060.4061725433783;1116.1405717597404;9878.753031174278;10455.111315538112;11093.1125509959;11106.167037472222;11480.827787583194;495.59361323714927;500.79717714325756;502.17392389519034;506.28360869370164;513.5352505982017;6421.550491465646;6559.573627714279;6648.368405001517;6734.629595372302;6743.797686069426;566194;573287;578978;589146;594828;84355.5782221085;84471;86061.56969215239;86356.56821715977;88416;507.9822206222838;511.9206522988868;513.4942082049179;513.8278676643366;514.423845293274;11032.117430605373;11038.779224415306;11119.666410007712;15605.219739013231;16142.757858632365;33150.5027424584;33806.15484612806;34229.65770342265;34269.48595771116;34280.485792713625;40868;42356.36465452959;43571.34642980421;48088.5191148098;50829.49170508247;10299.691009269734;10302.22733295021;10435.58257669715;10482.528286226985;10496.265261431932;12418.937905310415;12453.626391208278;12545.87454125447;17496.73754893703;18138.546536336697;14443.927780361451;14568.708625827212;15383.923080384526;15389.30748116315;15722.899397042098;11466.77066458683;14842.554723358317;15012.549623511311;15748;16012.27944742466;15572;15659.843401565839;15786.763198551802;15955.601109972344;16064.518064458085;10262.538185781508;10323.94838025805;10411.84382234252;10641.733956651145;10652.57389704403;15811.367545298059;15866.286017129027;15918.761218581962;15982.28079736438;16281.918590406973 16:50:52 INFO - 2016-02-25 16:50:52,213 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1528.266432112586;1535.5393381985402;1728.9221985010672;1775.5206094354526;1812.5196822841945;7950;8154.551499667523;8155.836883262338;8166.91833081669;8172.1827817218245;8368;8447.746567602966;8458.450200736948;8459.492430454176;8459.87310190346;2388.737238903719;2404.75952404759;2442.3161514775893;2450.424150324672;2451.4361696809747;2348.7064116985375;2386.0217310902535;2386.0455817672896;2386.8209884258663;2389.330987323552;2007.488090536911;2040.7551093868756;2379.809615230786;2380.5595964746576;2468.1978357033945;2387.1048356866177;2406.121765555577;2413.8431001984814;2419.9637005444933;2421.32202983165;2318.7797159269844;2371.9288421347387;2374.584447721642;2376.6791483149727;2378.6669866218745;6844.486663500207;6982.825429364256;7967.641456134488;8061.193880611951;8103.878441823377;4993.650444468889;5116.795328186905;5121.897562048775;5126.282320475137;5142.3829140503;3184.7611429142676;3187.984060079708;3210.678932106794;3213.967860321414;3249.106495713674;995.5420506566987;999.4503023337136;1025.7230547752065;1031.6853359725355;1042.3902017319876;11066.833997490045;11122.443877806078;11130.666080017612;11150.832737508943;11209.271397359145;4318.244307246282;4332.068605249887;4343.109662519176;4345.565443455629;4376.737395756265;9625.422474651543;9627.133557979816;9629.277804164716;9633.470159141314;9656.27577931658;4696.084263568571;4740.881477963009;4767.070421267889;4770.308305295782;4773.069251495925;4279;4283.057727299989;4287.571242875688;4291.3777502262;4293.806778694966;3777.9244415111543;3841.3469710149343;3848.730588858753;3873.4189871519498;3878.2243551289853;1251.4681260464267;1257.6730050187018;1257.7861763500227;1260.0801414967125;1269.9365031748375;1176.9588064417703;1182.532899504691;1195.557643671847;1199.2624535910527;1209.7096696792707;7557.886631700529;7650.4644674872225;7685.730999414994;7695.923040769632;7725.884111738328;1205.3009254632293;1217.7077501399776;1225.081189108169;1233.7224124572078;1234.660468371205;5675.091985282335;5750.482456578928;6036.698165091728;6085.9958106912745;6089.117078023662;9515.666951656658;9661.613535458502;9691.27315451344;9767.462789546644;9823.852642210373;9655.08276713711;9709.174720148725;9838.163756080814;9859.161971232526;9978.052085051908;7457.962710186523;7830.608469576612;7840.84318313642;7869.48848324857;7923.286904178652;7266.382357499671;7301.452391070585;7385.335319821242;7428.814279643052;7433.479656424215;8446.366522510714;8459.915400845914;8473.279771219515;8624;8686.652533898827;8651.610677519653;8821.691240806542;8846.33652476054;8859.468431894107;8868.423552469068;7912.28789408956;7941.563214023284;8044.2760151586635;8050.235227653364;8053.073896501977;7859.135495095649;7925.762227133196;7936.761897143095;7937.523748575104;7944.165862584577;7671.846563068819;7672.462927595015;7695.692172313269;7767.339776118867;7794.649240784065;7538.547687138789;7538.886916696144;7546.094468663796;7582;7634.503757255983;7768.689252430066;7817.0619525657285;7838.764837054898;7840.3727701784865;7841.411894108079;6716.126903502513;6833.863322733617;6858.2455929848675;6862.656867156722;6867.038614594061;6830.521863469509;6862.245153033063;6865.5537390069485;6866.073080134318;6874.9656251718425 16:50:52 INFO - 2016-02-25 16:50:52,217 DEBUG : BROWSER_OUTPUT: |4;prototype.html;361.779314618083;366.3259602331711;366.58758896241744;367.5479160632422;368.0798004987531;6174.228221472316;6218.129461875341;6218.968905155473;6231.875362492748;6246.625202487842;5826.126081087828;5937.435943585363;5953.613015154012;6110.755569777203;6115.69421528924;4771.475137734864;4773.403324584427;4776.593989510896;4784.83253086142;4785.976070119662;4573.542645735433;4773.379460670126;4792.496787837279;4798.712077275374;4800.8319708810195;4607.792649330788;4630.583247507724;4700.294955756647;4707.7881495332795;4754.524547545252;4705.458872229686;4722.905541889178;4736.029114031615;4740.620750339982;4749.691270067435;4202.747835129902;4210.157968406331;4212.726172798758;4216.156768646252;4223.303154979425;5748.2527271454865;5857.297124345106;5860.120981852713;5861.912071318934;5885.293764748215;5545.639533430314;5552.805651802208;5558.833235002957;5562.833115006556;5563.193336966157;6469.159009328804;6473.61158330497;6478;6479.092926990225;6485.643289619069;4688.29675548667;4689.109069276827;4698.788554515055;4702.670813043088;4710.599599034086;6543.116679248288;6718.428933540655;6719.865602687919;6736.056952026769;6753.662316884185;4667.13657973276;4695.178343789823;4699.600533954584;4702.976485117552;4703.435587729495;4717.127331443694;4751.596114330251;4751.596114330251;4751.738654374042;4757.452892917358;5317.973410132925;5458.426865179178;5458.890822183614;5462;5463.754131064112;6523.836904077341;6527.575707579087;6527.836804079936;6532.804015879531;6533.542652014408;1660.9584760380847;1681.0586071799587;1681.2602454919795;1682.4700219431086;1683.537027317473;1113.2318700096907;1123.7078359626391;1128.0129886349443;1128.7516746315798;1143.1712008793559;6256.874862502725;6287.811365659038;6290.308066112724;6298.496120310364;6308.968455157695;6641.535092543572;6737.3599508046655;6779.254282028973;6779.694913728894;6785.321467853274;6305.495560355161;6325.5255855811;6368.29948705642;6378.170837791057;6448.193975753031;3646.033801042726;3680.4847321375028;3694.390425579803;3698.352788262097;3703.0187000444903;6889.345512176335;7048.330408611174;7048.92951070493;7055.576665400093;7060.399866011447;7023.22744498115;7027.964860175666;7030.261822508665;7033.964830175919;7037.331453512011;7001.754938577125;7032.1912980007955;7036.085308909911;7041.4718896082995;7045.436365090781;6691.899621505779;6874.346937040973;6878.071460352989;6891.517593768388;6891.586504809728;6722.05891175226;6747.595144291358;6748.122744043243;6750.561213521106;6752.426043786137;7044.225135235222;7055.753048643272;7056.54132481387;7059.5058345915295;7065.258147894603;4598.540145985375;4699.718016918996;4723.7401942893475;4747;4747.667663263538;6814.591124532544;6817.420519255918;6819.624920629413;6825.624590647562;6831.453483721191;6585.045168450643;6617.404433600999;6625.436837868835;6628.171478565179;6634.734610615715;6413.647249401328;6612.23959244693;6615.933840661532;6616.908210145465;6618.238902526271;6493.642849643315;6496.415322620798;6499.415052645096;6503.902441463287;6504.934950650622;6633.535652504278;6645.734170633121;6645.767398141042;6651.767188148391;6654.667266636552;6276.905846412399;6354.17395738551;6477.093206950936;6477.319881412383;6493.707783149675 16:50:52 INFO - 2016-02-25 16:50:52,218 DEBUG : BROWSER_OUTPUT: |5;yui.html;808.6765293882448;885.225427750718;897.8698088777128;939.0984654731461;976.6142373762359;14846.554603361903;15055.171965541891;15089.62275943102;15145.848541514582;15168.696626067485;13421.865781342183;13464.394102265396;13472.663183420394;13494.932525337385;13525.188488690685;740.4335683202341;754.803751024732;884.8318819424321;903.5933829776594;904.1726819959729;885.1193062902421;887.2236792806294;893.4371346051978;893.580017391828;893.9150780675858;776.289694929142;786.2648423723808;923.6213152607432;924.0205382294755;929.1962452478625;922.2667978956712;934.0379409208526;934.56075644447;934.6401635370363;935.036911980661;684.4832151725416;688.6212583079287;688.9104416425832;690.5131882023162;691.022203581931;14006.369713362821;14066.507672231524;14084.366203520764;14138.646533836634;14155.716885662228;12280.201786883825;13320.734530219614;13734.793978090336;13921.164730116125;13945;10950.233483656148;11036.724081897937;11172.385518796464;11254.54981800727;11384.260023098472;3784.678302344304;3885.747426417302;3910.3156947534067;3928.3321835287784;3931.567527571965;47807.52192478065;48721.756391217816;49191;49513;49776.75111624491;9821.165200957856;10907.479599508104;10942;10942.835857462145;11066.28069175501;18921.7161742574;19073.427797166107;19094.14076366567;19126.808731912504;19136;7941.801454963672;7967.402444816662;8036.718714845069;8041.8793718094275;8166.591670416574;4694.43666759991;4715.22198837188;4753;4758.381410416624;4836.903261934742;3251.4797632378704;3255.121117298316;3265.053134590942;3288.473844184919;3293.143782616537;549.5191707256151;560.1989155507582;563.5970281248926;574.3854076138492;578.5371702637873;638.3552611862053;687.7283473028126;707.6391040569347;708.5075872268724;710.8755967705735;8935.910640893639;9070.455772653662;9262.120098590623;9320.263059932995;9330.486823224788;665.8135721997849;722.0613202836291;744.6090802328713;753.8040109571551;772.0966469231013;3892.649661530476;4262.9786851066165;4390.012247244408;4587.8623641290815;4660.976695116503;58762.41237587569;60422.09366859501;60451;60559.39440605626;64949.35050649433;54108.72945635246;54338.45661543334;54587.18119228215;55055;55239;48434;48856.511434885906;49554.504454954986;49893.75053124711;50189.24716129261;8818.955905220433;8963.59663815143;9023.95488022582;9115.27077833785;9202.677906273248;34471.138221544665;34975;35128.47307290444;35410.11474713153;35541.28917421689;42007.57992420036;44254.55745442504;46375.76812115918;46925.06149876915;47347.52652473569;9566.13904748576;11284.9435752824;11323.943380283046;11340;11410.14423918193;11088.5564577416;11173.776524469627;11292.378919159197;11305.830412543653;11369.374684392107;11688.70778230551;12007.579734709243;12128.696782580506;12371.443285052374;12431.564895228623;11071.833922491009;11116;11281.379524126252;11375.20373573842;11933.462994165457;10468.424236667057;12037.638870833889;12141.210821296587;12362.567310144103;12674.936625316814;10273.434961077213;10347.327423717434;10426.374417534973;10805.243632945618;10816.837747433952;9930;10357.4303413313;10423.21825863048;10469.842952355873;10615 16:50:52 INFO - 2016-02-25 16:50:52,218 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:50:52 INFO - 2016-02-25 16:50:52,218 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:50:52 INFO - 2016-02-25 16:50:52,218 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,743 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: __startTimestamp1456447852204__endTimestamp 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,219 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5626.61 Median:5847.24 stddev:638.67 (10.9%) stddev-sans-first:613.49 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: Values: 3278.5 3367.5 3390.5 3391.0 3423.6 5585.0 5596.0 5596.2 5600.6 5620.7 5610.8 5611.9 5621.8 5622.3 5628.8 4947.9 5093.7 5109.9 5166.1 5219.9 5044.3 5080.2 5148.9 5149.3 5161.5 5154.1 5158.3 5158.6 5167.5 5194.0 4974.1 5144.9 5156.5 5214.0 5232.3 4980.7 5056.9 5074.7 5087.7 5128.3 5412.2 5442.6 5443.6 5448.5 5451.3 5798.8 5904.0 6095.0 6101.3 6103.2 6065.2 6130.9 6142.4 6152.9 6157.8 5790.3 5794.6 5800.7 5812.4 5821.5 5994.4 6057.3 6088.9 6186.9 6279.3 6097.8 6099.5 6112.7 6128.1 6129.1 6131.3 6185.9 6187.7 6188.4 6203.6 5847.2 5875.8 5967.0 6044.3 6121.0 6016.5 6056.2 6076.6 6082.5 6094.0 4646.2 4650.7 4655.5 4655.7 4694.8 4037.2 4069.8 4102.9 4155.9 4159.7 5630.1 5662.6 5663.3 5665.3 5831.5 6070.1 6117.5 6121.9 6122.8 6127.2 5567.0 5644.6 5671.8 5757.3 5759.3 5258.5 5263.6 5264.8 5277.1 5288.3 6207.2 6212.2 6215.8 6216.1 6217.5 5972.4 5990.0 6084.1 6093.0 6108.7 6200.3 6202.2 6207.7 6208.0 6338.7 6046.5 6085.1 6098.2 6099.3 6104.3 5973.3 5992.0 6094.6 6152.8 6185.2 5965.2 5968.7 5970.2 5978.3 5990.9 5950.9 5963.0 5963.2 5963.9 5964.7 5665.2 5669.1 5731.6 5773.2 5822.1 5930.9 5931.1 5958.6 5994.9 6043.0 5642.1 5886.7 5891.9 5900.9 5901.7 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47874.83 Median:33616.33 stddev:76477.49 (227.5%) stddev-sans-first:76609.57 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: Values: 576.3 615.0 624.1 634.7 692.7 33961.3 43629.8 43640.3 43675.0 43681.8 47861.5 47998.0 48479.5 48560.5 48628.5 7863.4 7950.6 9162.9 9292.2 10567.7 10865.4 10936.3 10966.8 10970.6 11000.9 10899.2 10928.9 10985.1 10989.2 11039.1 10820.5 10901.2 10923.7 10965.5 10980.7 10765.5 10814.9 10867.4 10889.6 10893.1 43465.1 43833.6 43855.6 43884.6 43938.3 3326.9 3460.5 3718.2 3873.1 4179.8 32638.7 32939.3 33189.5 33275.8 33309.8 4316.8 4354.8 4356.8 4366.2 4390.8 288449.0 290223.0 290354.0 290447.0 290639.5 62908.7 64768.7 64783.7 65044.0 65643.7 70106.6 70915.6 71100.3 71301.0 71340.0 17353.4 17511.9 17633.3 17636.5 17714.8 13146.0 13262.1 13386.0 13491.7 13607.8 20741.8 21513.4 21546.9 21668.0 21697.0 7811.1 8124.0 8131.0 8159.1 8210.2 988.9 1044.1 1064.5 1088.6 1138.4 30503.4 30656.2 30664.2 31139.2 31302.5 1092.6 1109.6 1113.1 1127.2 1234.3 14197.6 16097.9 16273.9 16466.4 16515.8 340325.0 344185.0 346170.0 347064.0 347501.0 56801.1 56983.7 56990.4 57064.4 57109.0 1311.2 1394.6 1431.1 1449.1 1462.7 27450.2 27790.4 27810.2 27919.3 28771.9 42327.4 42444.6 42706.8 42719.1 42725.8 227933.0 233180.0 233434.0 234989.0 236184.0 33922.8 34045.5 34383.8 34820.0 34837.7 44936.3 45453.3 45789.8 46390.3 46527.5 37353.6 37786.8 37834.2 37915.4 39120.4 38612.0 38704.0 39318.2 39809.6 40286.4 35715.1 36139.0 38623.6 39074.0 39101.2 30035.0 31060.7 31712.0 31715.8 32318.4 25922.2 26067.5 26532.7 26640.6 26973.3 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,220 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30300.29 Median:33478.33 stddev:94962.99 (283.7%) stddev-sans-first:95202.82 16:50:52 INFO - 2016-02-25 16:50:52,221 DEBUG : BROWSER_OUTPUT: Values: 387.4 387.4 388.1 391.7 392.4 15031.7 16121.8 17160.0 18404.1 18432.5 18386.3 18411.9 18676.2 18680.0 18822.3 6151.1 6306.8 6312.4 6331.4 6348.7 4615.6 4739.2 6226.0 6770.0 6988.2 5441.5 6901.2 6926.2 6985.1 6991.1 5541.2 5545.2 5548.1 5563.9 5594.0 5174.5 5177.5 5180.6 5182.8 5229.3 13564.0 13802.9 16766.3 21831.0 21855.8 2258.9 2263.3 2292.2 2322.9 2360.2 9715.9 10131.9 10567.1 11386.4 13960.7 1980.9 1980.9 2002.9 2011.1 2051.7 66063.0 68449.0 68560.3 70507.0 70517.6 10746.5 10869.9 10905.1 11084.6 11184.9 14372.6 14494.2 14500.3 14507.1 14513.6 8320.4 8424.0 8506.8 8557.7 8574.7 8359.4 8366.5 8385.7 8417.7 8423.6 4137.1 4504.5 4520.7 4620.6 4702.2 1706.3 1903.4 1929.3 1930.4 1943.5 475.8 486.4 913.5 1060.4 1116.1 9878.8 10455.1 11093.1 11106.2 11480.8 495.6 500.8 502.2 506.3 513.5 6421.6 6559.6 6648.4 6734.6 6743.8 566194.0 573287.0 578978.0 589146.0 594828.0 84355.6 84471.0 86061.6 86356.6 88416.0 508.0 511.9 513.5 513.8 514.4 11032.1 11038.8 11119.7 15605.2 16142.8 33150.5 33806.2 34229.7 34269.5 34280.5 40868.0 42356.4 43571.3 48088.5 50829.5 10299.7 10302.2 10435.6 10482.5 10496.3 12418.9 12453.6 12545.9 17496.7 18138.5 14443.9 14568.7 15383.9 15389.3 15722.9 11466.8 14842.6 15012.5 15748.0 16012.3 15572.0 15659.8 15786.8 15955.6 16064.5 10262.5 10323.9 10411.8 10641.7 10652.6 15811.4 15866.3 15918.8 15982.3 16281.9 16:50:52 INFO - 2016-02-25 16:50:52,221 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,221 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5749.01 Median:6839.17 stddev:2977.22 (43.5%) stddev-sans-first:2968.67 16:50:52 INFO - 2016-02-25 16:50:52,222 DEBUG : BROWSER_OUTPUT: Values: 1528.3 1535.5 1728.9 1775.5 1812.5 7950.0 8154.6 8155.8 8166.9 8172.2 8368.0 8447.7 8458.5 8459.5 8459.9 2388.7 2404.8 2442.3 2450.4 2451.4 2348.7 2386.0 2386.0 2386.8 2389.3 2007.5 2040.8 2379.8 2380.6 2468.2 2387.1 2406.1 2413.8 2420.0 2421.3 2318.8 2371.9 2374.6 2376.7 2378.7 6844.5 6982.8 7967.6 8061.2 8103.9 4993.7 5116.8 5121.9 5126.3 5142.4 3184.8 3188.0 3210.7 3214.0 3249.1 995.5 999.5 1025.7 1031.7 1042.4 11066.8 11122.4 11130.7 11150.8 11209.3 4318.2 4332.1 4343.1 4345.6 4376.7 9625.4 9627.1 9629.3 9633.5 9656.3 4696.1 4740.9 4767.1 4770.3 4773.1 4279.0 4283.1 4287.6 4291.4 4293.8 3777.9 3841.3 3848.7 3873.4 3878.2 1251.5 1257.7 1257.8 1260.1 1269.9 1177.0 1182.5 1195.6 1199.3 1209.7 7557.9 7650.5 7685.7 7695.9 7725.9 1205.3 1217.7 1225.1 1233.7 1234.7 5675.1 5750.5 6036.7 6086.0 6089.1 9515.7 9661.6 9691.3 9767.5 9823.9 9655.1 9709.2 9838.2 9859.2 9978.1 7458.0 7830.6 7840.8 7869.5 7923.3 7266.4 7301.5 7385.3 7428.8 7433.5 8446.4 8459.9 8473.3 8624.0 8686.7 8651.6 8821.7 8846.3 8859.5 8868.4 7912.3 7941.6 8044.3 8050.2 8053.1 7859.1 7925.8 7936.8 7937.5 7944.2 7671.8 7672.5 7695.7 7767.3 7794.6 7538.5 7538.9 7546.1 7582.0 7634.5 7768.7 7817.1 7838.8 7840.4 7841.4 6716.1 6833.9 6858.2 6862.7 6867.0 6830.5 6862.2 6865.6 6866.1 6875.0 16:50:52 INFO - 2016-02-25 16:50:52,222 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,222 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5508.03 Median:6266.89 stddev:1648.41 (26.3%) stddev-sans-first:1607.14 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: Values: 361.8 366.3 366.6 367.5 368.1 6174.2 6218.1 6219.0 6231.9 6246.6 5826.1 5937.4 5953.6 6110.8 6115.7 4771.5 4773.4 4776.6 4784.8 4786.0 4573.5 4773.4 4792.5 4798.7 4800.8 4607.8 4630.6 4700.3 4707.8 4754.5 4705.5 4722.9 4736.0 4740.6 4749.7 4202.7 4210.2 4212.7 4216.2 4223.3 5748.3 5857.3 5860.1 5861.9 5885.3 5545.6 5552.8 5558.8 5562.8 5563.2 6469.2 6473.6 6478.0 6479.1 6485.6 4688.3 4689.1 4698.8 4702.7 4710.6 6543.1 6718.4 6719.9 6736.1 6753.7 4667.1 4695.2 4699.6 4703.0 4703.4 4717.1 4751.6 4751.6 4751.7 4757.5 5318.0 5458.4 5458.9 5462.0 5463.8 6523.8 6527.6 6527.8 6532.8 6533.5 1661.0 1681.1 1681.3 1682.5 1683.5 1113.2 1123.7 1128.0 1128.8 1143.2 6256.9 6287.8 6290.3 6298.5 6309.0 6641.5 6737.4 6779.3 6779.7 6785.3 6305.5 6325.5 6368.3 6378.2 6448.2 3646.0 3680.5 3694.4 3698.4 3703.0 6889.3 7048.3 7048.9 7055.6 7060.4 7023.2 7028.0 7030.3 7034.0 7037.3 7001.8 7032.2 7036.1 7041.5 7045.4 6691.9 6874.3 6878.1 6891.5 6891.6 6722.1 6747.6 6748.1 6750.6 6752.4 7044.2 7055.8 7056.5 7059.5 7065.3 4598.5 4699.7 4723.7 4747.0 4747.7 6814.6 6817.4 6819.6 6825.6 6831.5 6585.0 6617.4 6625.4 6628.2 6634.7 6413.6 6612.2 6615.9 6616.9 6618.2 6493.6 6496.4 6499.4 6503.9 6504.9 6633.5 6645.7 6645.8 6651.8 6654.7 6276.9 6354.2 6477.1 6477.3 6493.7 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14437.78 Median:25809.75 stddev:16655.20 (64.5%) stddev-sans-first:16670.48 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: Values: 808.7 885.2 897.9 939.1 976.6 14846.6 15055.2 15089.6 15145.8 15168.7 13421.9 13464.4 13472.7 13494.9 13525.2 740.4 754.8 884.8 903.6 904.2 885.1 887.2 893.4 893.6 893.9 776.3 786.3 923.6 924.0 929.2 922.3 934.0 934.6 934.6 935.0 684.5 688.6 688.9 690.5 691.0 14006.4 14066.5 14084.4 14138.6 14155.7 12280.2 13320.7 13734.8 13921.2 13945.0 10950.2 11036.7 11172.4 11254.5 11384.3 3784.7 3885.7 3910.3 3928.3 3931.6 47807.5 48721.8 49191.0 49513.0 49776.8 9821.2 10907.5 10942.0 10942.8 11066.3 18921.7 19073.4 19094.1 19126.8 19136.0 7941.8 7967.4 8036.7 8041.9 8166.6 4694.4 4715.2 4753.0 4758.4 4836.9 3251.5 3255.1 3265.1 3288.5 3293.1 549.5 560.2 563.6 574.4 578.5 638.4 687.7 707.6 708.5 710.9 8935.9 9070.5 9262.1 9320.3 9330.5 665.8 722.1 744.6 753.8 772.1 3892.6 4263.0 4390.0 4587.9 4661.0 58762.4 60422.1 60451.0 60559.4 64949.4 54108.7 54338.5 54587.2 55055.0 55239.0 48434.0 48856.5 49554.5 49893.8 50189.2 8819.0 8963.6 9024.0 9115.3 9202.7 34471.1 34975.0 35128.5 35410.1 35541.3 42007.6 44254.6 46375.8 46925.1 47347.5 9566.1 11284.9 11323.9 11340.0 11410.1 11088.6 11173.8 11292.4 11305.8 11369.4 11688.7 12007.6 12128.7 12371.4 12431.6 11071.8 11116.0 11281.4 11375.2 11933.5 10468.4 12037.6 12141.2 12362.6 12674.9 10273.4 10347.3 10426.4 10805.2 10816.8 9930.0 10357.4 10423.2 10469.8 10615.0 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:50:52 INFO - 2016-02-25 16:50:52,223 DEBUG : BROWSER_OUTPUT: 16:50:52 INFO - 2016-02-25 16:50:52,729 INFO : Browser exited with error code: 0 16:50:52 INFO - 2016-02-25 16:50:52,753 INFO : Completed test dromaeo_css (00:18:29) 16:50:52 INFO - 2016-02-25 16:50:52,753 INFO : Starting test kraken 16:50:52 INFO - 2016-02-25 16:50:52,753 DEBUG : operating with platform_type : linux_ 16:50:52 INFO - 2016-02-25 16:50:52,753 INFO : Initialising browser for kraken test... 16:50:52 INFO - 2016-02-25 16:50:52,759 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeXhD6E/profile http://localhost:57086/getInfo.html 16:50:56 INFO - 2016-02-25 16:50:56,134 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:50:56 INFO - 2016-02-25 16:50:56,134 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:50:56 INFO - 2016-02-25 16:50:56,134 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:50:56 INFO - 2016-02-25 16:50:56,134 DEBUG : BROWSER_OUTPUT: __metrics 16:50:56 INFO - 2016-02-25 16:50:56,275 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] 16:50:57 INFO - 2016-02-25 16:50:57,269 INFO : Browser initialized. 16:50:57 INFO - 2016-02-25 16:50:57,270 INFO : Running cycle 1/1 for kraken test... 16:50:57 INFO - 2016-02-25 16:50:57,270 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpeXhD6E/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:51:08 INFO - 2016-02-25 16:51:08,952 DEBUG : BROWSER_OUTPUT: RSS: Main: 132575232 16:51:08 INFO - 2016-02-25 16:51:08,952 DEBUG : BROWSER_OUTPUT: 16:51:11 INFO - 2016-02-25 16:51:11,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57086/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:11 INFO - 2016-02-25 16:51:11,981 DEBUG : BROWSER_OUTPUT: RSS: Main: 164012032 16:51:11 INFO - 2016-02-25 16:51:11,982 DEBUG : BROWSER_OUTPUT: 16:51:14 INFO - 2016-02-25 16:51:14,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57086/tests/kraken/driver.html?testName=audio-dft) 16:51:15 INFO - 2016-02-25 16:51:15,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 163205120 16:51:15 INFO - 2016-02-25 16:51:15,171 DEBUG : BROWSER_OUTPUT: 16:51:17 INFO - 2016-02-25 16:51:17,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57086/tests/kraken/driver.html?testName=audio-fft) 16:51:18 INFO - 2016-02-25 16:51:18,531 DEBUG : BROWSER_OUTPUT: RSS: Main: 158683136 16:51:18 INFO - 2016-02-25 16:51:18,531 DEBUG : BROWSER_OUTPUT: 16:51:20 INFO - 2016-02-25 16:51:20,510 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57086/tests/kraken/driver.html?testName=audio-oscillator) 16:51:21 INFO - 2016-02-25 16:51:21,076 DEBUG : BROWSER_OUTPUT: RSS: Main: 158978048 16:51:21 INFO - 2016-02-25 16:51:21,076 DEBUG : BROWSER_OUTPUT: 16:51:23 INFO - 2016-02-25 16:51:23,229 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57086/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:23 INFO - 2016-02-25 16:51:23,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 157683712 16:51:23 INFO - 2016-02-25 16:51:23,778 DEBUG : BROWSER_OUTPUT: 16:51:26 INFO - 2016-02-25 16:51:26,635 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57086/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:27 INFO - 2016-02-25 16:51:27,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 157548544 16:51:27 INFO - 2016-02-25 16:51:27,202 DEBUG : BROWSER_OUTPUT: 16:51:30 INFO - 2016-02-25 16:51:30,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57086/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:31 INFO - 2016-02-25 16:51:31,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 158408704 16:51:31 INFO - 2016-02-25 16:51:31,119 DEBUG : BROWSER_OUTPUT: 16:51:33 INFO - 2016-02-25 16:51:33,821 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57086/tests/kraken/driver.html?testName=json-parse-financial) 16:51:34 INFO - 2016-02-25 16:51:34,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 158670848 16:51:34 INFO - 2016-02-25 16:51:34,388 DEBUG : BROWSER_OUTPUT: 16:51:35 INFO - 2016-02-25 16:51:35,921 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57086/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:51:36 INFO - 2016-02-25 16:51:36,475 DEBUG : BROWSER_OUTPUT: RSS: Main: 166723584 16:51:36 INFO - 2016-02-25 16:51:36,475 DEBUG : BROWSER_OUTPUT: 16:51:38 INFO - 2016-02-25 16:51:38,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:51:38 INFO - 2016-02-25 16:51:38,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 164245504 16:51:38 INFO - 2016-02-25 16:51:38,803 DEBUG : BROWSER_OUTPUT: 16:51:40 INFO - 2016-02-25 16:51:40,775 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:41 INFO - 2016-02-25 16:51:41,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 171606016 16:51:41 INFO - 2016-02-25 16:51:41,341 DEBUG : BROWSER_OUTPUT: 16:51:43 INFO - 2016-02-25 16:51:43,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:44 INFO - 2016-02-25 16:51:44,262 DEBUG : BROWSER_OUTPUT: RSS: Main: 178622464 16:51:44 INFO - 2016-02-25 16:51:44,262 DEBUG : BROWSER_OUTPUT: 16:51:46 INFO - 2016-02-25 16:51:46,717 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:47 INFO - 2016-02-25 16:51:47,290 DEBUG : BROWSER_OUTPUT: RSS: Main: 176832512 16:51:47 INFO - 2016-02-25 16:51:47,290 DEBUG : BROWSER_OUTPUT: 16:51:48 INFO - 2016-02-25 16:51:48,940 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57086/tests/kraken/driver.html?testName=ai-astar) 16:51:49 INFO - 2016-02-25 16:51:49,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 233877504 16:51:49 INFO - 2016-02-25 16:51:49,190 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,190 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:51:49 INFO - 2016-02-25 16:51:49,191 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:51:49 INFO - 2016-02-25 16:51:49,191 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:51:49 INFO - 2016-02-25 16:51:49,191 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:51:49 INFO - 2016-02-25 16:51:49,191 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;109;104;109;109;111;101;110;109;108 16:51:49 INFO - 2016-02-25 16:51:49,191 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;121;166;168;123;150;157;124;161;123 16:51:49 INFO - 2016-02-25 16:51:49,192 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;161;161;160;162;159;163;161;160 16:51:49 INFO - 2016-02-25 16:51:49,192 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;70;72;157;72;71;72;71;146;74 16:51:49 INFO - 2016-02-25 16:51:49,192 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;136;92;93;93;135;97;92;133 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;108;109;110;110;109;109 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;147;146;146;146;146;145 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;56;57;55;58;55;57;57;58;54 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;65;64;65;65;66;65;65;64 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;86;78;79;78;78;78;86;79;77 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;110;108;111;113;116;126;127;114;118 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;138;144;138;140;141;141;140;140;139 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;63;63;63;63;64;70;65;63;64 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:51:49 INFO - 2016-02-25 16:51:49,194 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,876 16:51:49 INFO - 2016-02-25 16:51:49,195 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:51:49 INFO - 2016-02-25 16:51:49,195 DEBUG : BROWSER_OUTPUT: __startTimestamp1456447909190__endTimestamp 16:51:49 INFO - 2016-02-25 16:51:49,195 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,195 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:51:49 INFO - 2016-02-25 16:51:49,196 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:51:49 INFO - 2016-02-25 16:51:49,196 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,196 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:3.03 (2.8%) stddev-sans-first:3.19 16:51:49 INFO - 2016-02-25 16:51:49,196 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 104.0 109.0 109.0 111.0 101.0 110.0 109.0 108.0 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.80 Median:153.50 stddev:19.34 (12.6%) stddev-sans-first:20.51 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: Values: 145.0 121.0 166.0 168.0 123.0 150.0 157.0 124.0 161.0 123.0 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 161.0 161.0 160.0 162.0 159.0 163.0 161.0 160.0 16:51:49 INFO - 2016-02-25 16:51:49,197 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:71.50 stddev:33.79 (47.3%) stddev-sans-first:35.31 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 72.0 157.0 72.0 71.0 72.0 71.0 146.0 74.0 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:93.00 stddev:20.05 (21.6%) stddev-sans-first:20.73 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 136.0 92.0 93.0 93.0 135.0 97.0 92.0 133.0 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,198 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 108.0 109.0 110.0 110.0 109.0 109.0 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 145.0 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.58 (2.8%) stddev-sans-first:1.41 16:51:49 INFO - 2016-02-25 16:51:49,199 DEBUG : BROWSER_OUTPUT: Values: 59.0 56.0 57.0 55.0 58.0 55.0 57.0 57.0 58.0 54.0 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 65.0 64.0 65.0 65.0 66.0 65.0 65.0 64.0 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:78.50 stddev:3.44 (4.4%) stddev-sans-first:3.52 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: Values: 77.0 86.0 78.0 79.0 78.0 78.0 78.0 86.0 79.0 77.0 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.90 Median:116.00 stddev:6.35 (5.5%) stddev-sans-first:6.74 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: Values: 116.0 110.0 108.0 111.0 113.0 116.0 126.0 127.0 114.0 118.0 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,200 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.40 Median:140.50 stddev:1.96 (1.4%) stddev-sans-first:1.83 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: Values: 143.0 138.0 144.0 138.0 140.0 141.0 141.0 140.0 140.0 139.0 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.00 stddev:2.32 (3.6%) stddev-sans-first:2.28 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: Values: 67.0 63.0 63.0 63.0 63.0 64.0 70.0 65.0 63.0 64.0 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:51:49 INFO - 2016-02-25 16:51:49,201 DEBUG : BROWSER_OUTPUT: 16:51:49 INFO - 2016-02-25 16:51:49,748 INFO : Browser exited with error code: 0 16:51:49 INFO - 2016-02-25 16:51:49,805 INFO : Completed test kraken (00:00:57) 16:51:49 INFO - 2016-02-25 16:51:49,805 INFO : Starting test v8_7 16:51:49 INFO - 2016-02-25 16:51:49,805 DEBUG : operating with platform_type : linux_ 16:51:49 INFO - 2016-02-25 16:51:49,805 INFO : Initialising browser for v8_7 test... 16:51:49 INFO - 2016-02-25 16:51:49,811 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKuNWK0/profile http://localhost:57086/getInfo.html 16:51:53 INFO - 2016-02-25 16:51:53,383 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:51:53 INFO - 2016-02-25 16:51:53,383 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:51:53 INFO - 2016-02-25 16:51:53,384 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:51:53 INFO - 2016-02-25 16:51:53,384 DEBUG : BROWSER_OUTPUT: __metrics 16:51:53 INFO - 2016-02-25 16:51:53,520 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] 16:51:54 INFO - 2016-02-25 16:51:54,393 INFO : Browser initialized. 16:51:54 INFO - 2016-02-25 16:51:54,394 INFO : Running cycle 1/1 for v8_7 test... 16:51:54 INFO - 2016-02-25 16:51:54,394 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKuNWK0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:52:06 INFO - 2016-02-25 16:52:06,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 134471680 16:52:06 INFO - 2016-02-25 16:52:06,059 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,402 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57086/tests/v8_7/run.html (next: http://localhost:57086/tests/v8_7/run.html) 16:52:27 INFO - 2016-02-25 16:52:27,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 422207488 16:52:27 INFO - 2016-02-25 16:52:27,652 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,652 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:52:27 INFO - 2016-02-25 16:52:27,652 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |0;Richards;167.81339150864238 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.25204883759827 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 16:52:27 INFO - 2016-02-25 16:52:27,653 DEBUG : BROWSER_OUTPUT: |4;RayTrace;925.925925925926 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: |5;Earley;539.6654074473827 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: |6;Boyer;10479.166666666666 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: |7;RegExp;24333.333333333332 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: |8;Splay;514.40329218107 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 16:52:27 INFO - 2016-02-25 16:52:27,654 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:52:27 INFO - 2016-02-25 16:52:27,655 DEBUG : BROWSER_OUTPUT: __startTimestamp1456447947651__endTimestamp 16:52:27 INFO - 2016-02-25 16:52:27,655 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,655 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:52:27 INFO - 2016-02-25 16:52:27,655 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:52:27 INFO - 2016-02-25 16:52:27,655 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,656 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.81 Median:167.81 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,656 DEBUG : BROWSER_OUTPUT: Values: 167.8 16:52:27 INFO - 2016-02-25 16:52:27,656 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,656 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.25 Median:167.25 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,657 DEBUG : BROWSER_OUTPUT: Values: 167.3 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: Values: 278.1 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: Values: 5148.7 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,658 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:925.93 Median:925.93 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: Values: 925.9 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.67 Median:539.67 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: Values: 539.7 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10479.17 Median:10479.17 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,659 DEBUG : BROWSER_OUTPUT: Values: 10479.2 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24333.33 Median:24333.33 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: Values: 24333.3 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:514.40 Median:514.40 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: Values: 514.4 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: 16:52:27 INFO - 2016-02-25 16:52:27,660 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 16:52:27 INFO - 2016-02-25 16:52:27,661 DEBUG : BROWSER_OUTPUT: Values: 4826.9 16:52:27 INFO - 2016-02-25 16:52:27,661 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:52:27 INFO - 2016-02-25 16:52:27,661 DEBUG : BROWSER_OUTPUT: 16:52:28 INFO - 2016-02-25 16:52:28,361 INFO : Browser exited with error code: 0 16:52:28 INFO - 2016-02-25 16:52:28,376 INFO : Completed test v8_7 (00:00:38) 16:52:28 INFO - 2016-02-25 16:52:28,730 INFO : Completed test suite (00:20:05) 16:52:28 INFO - 2016-02-25 16:52:28,730 DEBUG : Working with test: dromaeo_css 16:52:28 INFO - 2016-02-25 16:52:28,730 DEBUG : Generating results file: dromaeo_css 16:52:28 INFO - 2016-02-25 16:52:28,732 DEBUG : Working with test: kraken 16:52:28 INFO - 2016-02-25 16:52:28,732 DEBUG : Generating results file: kraken 16:52:28 INFO - 2016-02-25 16:52:28,732 INFO : javascript benchmark 16:52:28 INFO - 2016-02-25 16:52:28,733 DEBUG : Working with test: v8_7 16:52:28 INFO - 2016-02-25 16:52:28,733 DEBUG : Generating results file: v8_7 16:52:28 INFO - 2016-02-25 16:52:28,733 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:52:28 INFO - 2016-02-25 16:52:28,780 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:52:28 INFO - 2016-02-25 16:52:28,781 DEBUG : process_Request line: dromaeo_css 7309.75 graph.html#tests=[[72,53,35]] 16:52:28 INFO - 2016-02-25 16:52:28,781 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:52:28 INFO - 2016-02-25 16:52:28,808 DEBUG : process_Request line: kraken 1478.00 graph.html#tests=[[232,53,35]] 16:52:28 INFO - 2016-02-25 16:52:28,808 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:52:28 INFO - 2016-02-25 16:52:28,844 DEBUG : process_Request line: v8_7 23386.68 graph.html#tests=[[230,53,35]] 16:52:28 INFO - 2016-02-25 16:52:28,845 INFO : javascript benchmark 16:52:28 INFO - 2016-02-25 16:52:28,849 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3278.4754439289713, 3367.4780409036575, 3390.4914262860584, 3390.9830450847744, 3423.6405177456368, 5584.9720751396235, 5596.048671725806, 5596.160575913615, 5600.6079574429805, 5620.747066382018, 5610.775568977247, 5611.915821262684, 5621.7751289948455, 5622.269105016352, 5628.802991895284, 4947.901041979158, 5093.694378337303, 5109.897802043957, 5166.147585648364, 5219.86950326243, 5044.2937988681615, 5080.187170052793, 5148.922766158511, 5149.304843846089, 5161.483851614846, 5154.149565321717, 5158.277841102249, 5158.587313014949, 5167.534921857032, 5193.974030129844, 4974.129527332739, 5144.922826157611, 5156.458571849977, 5214.0, 5232.345956755386, 4980.651354405193, 5056.924146137811, 5074.695518268915, 5087.720175390389, 5128.282040514331, 5412.242286079952, 5442.564594832404, 5443.55139721803, 5448.482394172547, 5451.291332126798, 5798.82603521895, 5904.0258357370985, 6094.96952515239, 6101.267847858241, 6103.23709536308, 6065.2418447694035, 6130.938690613126, 6142.447179753844, 6152.907706384408, 6157.815265542041, 5790.305163380422, 5794.62334948227, 5800.738966746507, 5812.360640329561, 5821.476067153934, 5994.400559943971, 6057.303410107806, 6088.878222435526, 6186.876262474815, 6279.340669229757, 6097.774312375951, 6099.451049405575, 6112.663803490762, 6128.050152226412, 6129.08063790435, 6131.295214939155, 6185.907211391833, 6187.690615469209, 6188.412100850412, 6203.627782333075, 5847.239858818326, 5875.823725288248, 5967.0, 6044.304904935989, 6120.990036643993, 6016.488598469092, 6056.182415373956, 6076.605020673642, 6082.543809214327, 6094.0, 4646.233371493733, 4650.697704649187, 4655.534446555385, 4655.720656760605, 4694.81220751173, 4037.212743515045, 4069.816858241327, 4102.876913692594, 4155.916881662411, 4159.708820382604, 5630.0991841305195, 5662.6036177468, 5663.263775709132, 5665.291838520185, 5831.533477321804, 6070.0591408331775, 6117.541184411187, 6121.908171377433, 6122.846928826815, 6127.234095738033, 5566.972165139229, 5644.57665675076, 5671.801486947937, 5757.337906140764, 5759.2512973313205, 5258.474152584711, 5263.631545791758, 5264.842054738364, 5277.050130976461, 5288.312519372457, 6207.193064901534, 6212.161358216583, 6215.782447614311, 6216.129741836146, 6217.533684973645, 5972.432618901197, 5989.981703110395, 6084.11780291864, 6093.025115981422, 6108.72510737009, 6200.348963358782, 6202.224721909762, 6207.6585787782105, 6207.96896015517, 6338.683065846782, 6046.516278697605, 6085.1480792689945, 6098.237720284965, 6099.268087829489, 6104.267487901303, 5973.253343332083, 5992.0, 6094.634321940698, 6152.815415537541, 6185.195924529782, 5965.194698715618, 5968.701564921824, 5970.223870896929, 5978.312494063239, 5990.850228744316, 5950.910736338872, 5963.0, 5963.165156878127, 5963.9105413417965, 5964.731587078679, 5665.178549110438, 5669.149627555735, 5731.627444216113, 5773.191753154646, 5822.097574875901, 5930.851728706817, 5931.13998470228, 5958.582899197015, 5994.880102398015, 6042.969785151046, 5642.097264437834, 5886.735096920735, 5891.9116213255975, 5900.852478688067, 5901.704914754159], "unit": "ms", "name": "dojo.html", "value": 5584.245025940945}, {"lowerIsBetter": false, "replicates": [576.2997957481659, 615.0374663651386, 624.1106423346731, 634.7016902056032, 692.6882902693789, 33961.32077358451, 43629.78185109074, 43640.34539481902, 43675.0, 43681.781591092, 47861.521384786145, 47998.0, 48479.51520484794, 48560.51439485604, 48628.51371486284, 7863.449558530905, 7950.602469876512, 9162.862557061633, 9292.163705266525, 10567.73580660482, 10865.402402867878, 10936.289141205836, 10966.835497467544, 10970.561177552889, 11000.889991100066, 10899.182561307895, 10928.890711092907, 10985.121190304757, 10989.230753847234, 11039.06167975719, 10820.513076911559, 10901.236913416064, 10923.726906827353, 10965.451727413678, 10980.725481862937, 10765.51555180011, 10814.945925270324, 10867.402292873889, 10889.618863339745, 10893.128549716004, 43465.130697386194, 43833.56166438359, 43855.561444385465, 43884.561154388684, 43938.34092488583, 3326.8835590754206, 3460.463628137643, 3718.163413232001, 3873.1285460771383, 4179.832806687728, 32638.6736132638, 32939.341213175605, 33189.50215746766, 33275.83362083198, 33309.83345083283, 4316.848910288125, 4354.782260886943, 4356.76037817923, 4366.192254432908, 4390.802413891351, 288449.0, 290223.0, 290354.0, 290447.0, 290639.54680226673, 62908.68545657334, 64768.676156618916, 64783.67608162023, 65044.0, 65643.67178164174, 70106.64946675336, 70915.64542177255, 71100.28899710937, 71301.0, 71340.0, 17353.392631257844, 17511.912440437718, 17633.294668213384, 17636.470905872844, 17714.822851771576, 13146.0, 13262.137961032502, 13386.0, 13491.730165396637, 13607.795883061763, 20741.79258207429, 21513.354599362043, 21546.892265538572, 21668.0, 21697.0, 7811.140774514799, 8124.038442326435, 8130.959345203236, 8159.102498725136, 8210.220029097227, 988.8862780780158, 1044.070777008463, 1064.4730858225073, 1088.5754555723274, 1138.3909608359488, 30503.389932201677, 30656.23359415988, 30664.23339416488, 31139.221519462193, 31302.530462043087, 1092.5848177692585, 1109.5839060352369, 1113.1039513191797, 1127.2053202492223, 1234.2779474007807, 14197.574072777834, 16097.919510402608, 16273.918630406772, 16466.447700606426, 16515.752263716055, 340325.0, 344185.0, 346170.0, 347064.0, 347501.0, 56801.14798278029, 56983.71508142433, 56990.43009569934, 57064.42935570674, 57109.0, 1311.1805121798875, 1394.6234516680643, 1431.119861285323, 1449.0943160524882, 1462.700146469971, 27450.176494705192, 27790.44419111647, 27810.16569502918, 27919.302017449725, 28771.856140719163, 42327.3650895243, 42444.57555424406, 42706.78646606747, 42719.14561708686, 42725.786371067945, 227933.0, 233180.0, 233434.0, 234989.0, 236184.0, 33922.83038584791, 34045.48931765976, 34383.82808086044, 34820.0, 34837.65162348445, 44936.32595511005, 45453.318200226364, 45789.771051145864, 46390.30414543717, 46527.53472465232, 37353.6264637361, 37786.811065944494, 37834.243315134096, 37915.43126853044, 39120.413193802684, 38612.0, 38704.0, 39318.2136357277, 39809.60190398059, 40286.39570406505, 35715.10712232215, 36139.0, 38623.613763862, 39074.0, 39101.217975640895, 30035.0, 31060.68939310578, 31712.0, 31715.84142079275, 32318.35363292768, 25922.19250269493, 26067.478650426507, 26532.734672653027, 26640.600390993764, 26973.3256668577], "unit": "ms", "name": "ext.html", "value": 20004.509306976855}, {"lowerIsBetter": false, "replicates": [387.41306920016183, 387.4246743585775, 388.06089263981164, 391.68860755699217, 392.4211787613269, 15031.735435079354, 16121.758173627404, 17160.0, 18404.079796010195, 18432.539186520342, 18386.264549418007, 18411.90794046031, 18676.159572819222, 18680.0, 18822.341218057372, 6151.077338399231, 6306.842328941791, 6312.400321969406, 6331.366863313678, 6348.682565871711, 4615.584597386234, 4739.241721324589, 6226.0, 6769.966150169243, 6988.196357418911, 5441.455854414547, 6901.171859376882, 6926.168859736838, 6985.05701730265, 6991.056207411985, 5541.224228607978, 5545.168224766277, 5548.0845660465975, 5563.916541251884, 5594.049011668006, 5174.4825517448335, 5177.508136727005, 5180.585553155738, 5182.792688292464, 5229.320188375525, 13564.0, 13802.861971380258, 16766.32934682611, 21831.01760420785, 21855.781442185693, 2258.8644681319174, 2263.2757517594378, 2292.1862738727677, 2322.8722420266877, 2360.1503458754833, 9715.854262186072, 10131.949340253252, 10567.10179634732, 11386.373749443777, 13960.650983725387, 1980.861339706221, 1980.9405717828488, 2002.8698134621202, 2011.1452632631074, 2051.671732522804, 66063.0, 68449.0, 68560.3143968564, 70507.0, 70517.64741176313, 10746.516406761715, 10869.891301087046, 10905.127589792797, 11084.612038578773, 11184.94407527957, 14372.640683982983, 14494.202818844853, 14500.347484363228, 14507.1295722257, 14513.564593062225, 8320.417570769989, 8424.0, 8506.829863402698, 8557.700480483278, 8574.699885503978, 8359.373047021498, 8366.53984030872, 8385.706500272583, 8417.747467575982, 8423.610104332716, 4137.13120244752, 4504.549545045469, 4520.728756274634, 4620.561046700558, 4702.153612349742, 1706.2744026418566, 1903.448000079989, 1929.2958070304237, 1930.420873737873, 1943.5044063763933, 475.7882742179697, 486.44302273896193, 913.4975763330142, 1060.4061725433783, 1116.1405717597404, 9878.753031174278, 10455.111315538112, 11093.1125509959, 11106.167037472222, 11480.827787583194, 495.59361323714927, 500.79717714325756, 502.17392389519034, 506.28360869370164, 513.5352505982017, 6421.550491465646, 6559.573627714279, 6648.368405001517, 6734.629595372302, 6743.797686069426, 566194.0, 573287.0, 578978.0, 589146.0, 594828.0, 84355.5782221085, 84471.0, 86061.56969215239, 86356.56821715977, 88416.0, 507.9822206222838, 511.9206522988868, 513.4942082049179, 513.8278676643366, 514.423845293274, 11032.117430605373, 11038.779224415306, 11119.666410007712, 15605.219739013231, 16142.757858632365, 33150.5027424584, 33806.15484612806, 34229.65770342265, 34269.48595771116, 34280.485792713625, 40868.0, 42356.36465452959, 43571.34642980421, 48088.5191148098, 50829.49170508247, 10299.691009269734, 10302.22733295021, 10435.58257669715, 10482.528286226985, 10496.265261431932, 12418.937905310415, 12453.626391208278, 12545.87454125447, 17496.73754893703, 18138.546536336697, 14443.927780361451, 14568.708625827212, 15383.923080384526, 15389.30748116315, 15722.899397042098, 11466.77066458683, 14842.554723358317, 15012.549623511311, 15748.0, 16012.27944742466, 15572.0, 15659.843401565839, 15786.763198551802, 15955.601109972344, 16064.518064458085, 10262.538185781508, 10323.94838025805, 10411.84382234252, 10641.733956651145, 10652.57389704403, 15811.367545298059, 15866.286017129027, 15918.761218581962, 15982.28079736438, 16281.918590406973], "unit": "ms", "name": "jquery.html", "value": 8708.072265165094}, {"lowerIsBetter": false, "replicates": [1528.266432112586, 1535.5393381985402, 1728.9221985010672, 1775.5206094354526, 1812.5196822841945, 7950.0, 8154.551499667523, 8155.836883262338, 8166.91833081669, 8172.1827817218245, 8368.0, 8447.746567602966, 8458.450200736948, 8459.492430454176, 8459.87310190346, 2388.737238903719, 2404.75952404759, 2442.3161514775893, 2450.424150324672, 2451.4361696809747, 2348.7064116985375, 2386.0217310902535, 2386.0455817672896, 2386.8209884258663, 2389.330987323552, 2007.488090536911, 2040.7551093868756, 2379.809615230786, 2380.5595964746576, 2468.1978357033945, 2387.1048356866177, 2406.121765555577, 2413.8431001984814, 2419.9637005444933, 2421.32202983165, 2318.7797159269844, 2371.9288421347387, 2374.584447721642, 2376.6791483149727, 2378.6669866218745, 6844.486663500207, 6982.825429364256, 7967.641456134488, 8061.193880611951, 8103.878441823377, 4993.650444468889, 5116.795328186905, 5121.897562048775, 5126.282320475137, 5142.3829140503, 3184.7611429142676, 3187.984060079708, 3210.678932106794, 3213.967860321414, 3249.106495713674, 995.5420506566987, 999.4503023337136, 1025.7230547752065, 1031.6853359725355, 1042.3902017319876, 11066.833997490045, 11122.443877806078, 11130.666080017612, 11150.832737508943, 11209.271397359145, 4318.244307246282, 4332.068605249887, 4343.109662519176, 4345.565443455629, 4376.737395756265, 9625.422474651543, 9627.133557979816, 9629.277804164716, 9633.470159141314, 9656.27577931658, 4696.084263568571, 4740.881477963009, 4767.070421267889, 4770.308305295782, 4773.069251495925, 4279.0, 4283.057727299989, 4287.571242875688, 4291.3777502262, 4293.806778694966, 3777.9244415111543, 3841.3469710149343, 3848.730588858753, 3873.4189871519498, 3878.2243551289853, 1251.4681260464267, 1257.6730050187018, 1257.7861763500227, 1260.0801414967125, 1269.9365031748375, 1176.9588064417703, 1182.532899504691, 1195.557643671847, 1199.2624535910527, 1209.7096696792707, 7557.886631700529, 7650.4644674872225, 7685.730999414994, 7695.923040769632, 7725.884111738328, 1205.3009254632293, 1217.7077501399776, 1225.081189108169, 1233.7224124572078, 1234.660468371205, 5675.091985282335, 5750.482456578928, 6036.698165091728, 6085.9958106912745, 6089.117078023662, 9515.666951656658, 9661.613535458502, 9691.27315451344, 9767.462789546644, 9823.852642210373, 9655.08276713711, 9709.174720148725, 9838.163756080814, 9859.161971232526, 9978.052085051908, 7457.962710186523, 7830.608469576612, 7840.84318313642, 7869.48848324857, 7923.286904178652, 7266.382357499671, 7301.452391070585, 7385.335319821242, 7428.814279643052, 7433.479656424215, 8446.366522510714, 8459.915400845914, 8473.279771219515, 8624.0, 8686.652533898827, 8651.610677519653, 8821.691240806542, 8846.33652476054, 8859.468431894107, 8868.423552469068, 7912.28789408956, 7941.563214023284, 8044.2760151586635, 8050.235227653364, 8053.073896501977, 7859.135495095649, 7925.762227133196, 7936.761897143095, 7937.523748575104, 7944.165862584577, 7671.846563068819, 7672.462927595015, 7695.692172313269, 7767.339776118867, 7794.649240784065, 7538.547687138789, 7538.886916696144, 7546.094468663796, 7582.0, 7634.503757255983, 7768.689252430066, 7817.0619525657285, 7838.764837054898, 7840.3727701784865, 7841.411894108079, 6716.126903502513, 6833.863322733617, 6858.2455929848675, 6862.656867156722, 6867.038614594061, 6830.521863469509, 6862.245153033063, 6865.5537390069485, 6866.073080134318, 6874.9656251718425], "unit": "ms", "name": "mootools.html", "value": 4720.205493213944}, {"lowerIsBetter": false, "replicates": [361.779314618083, 366.3259602331711, 366.58758896241744, 367.5479160632422, 368.0798004987531, 6174.228221472316, 6218.129461875341, 6218.968905155473, 6231.875362492748, 6246.625202487842, 5826.126081087828, 5937.435943585363, 5953.613015154012, 6110.755569777203, 6115.69421528924, 4771.475137734864, 4773.403324584427, 4776.593989510896, 4784.83253086142, 4785.976070119662, 4573.542645735433, 4773.379460670126, 4792.496787837279, 4798.712077275374, 4800.8319708810195, 4607.792649330788, 4630.583247507724, 4700.294955756647, 4707.7881495332795, 4754.524547545252, 4705.458872229686, 4722.905541889178, 4736.029114031615, 4740.620750339982, 4749.691270067435, 4202.747835129902, 4210.157968406331, 4212.726172798758, 4216.156768646252, 4223.303154979425, 5748.2527271454865, 5857.297124345106, 5860.120981852713, 5861.912071318934, 5885.293764748215, 5545.639533430314, 5552.805651802208, 5558.833235002957, 5562.833115006556, 5563.193336966157, 6469.159009328804, 6473.61158330497, 6478.0, 6479.092926990225, 6485.643289619069, 4688.29675548667, 4689.109069276827, 4698.788554515055, 4702.670813043088, 4710.599599034086, 6543.116679248288, 6718.428933540655, 6719.865602687919, 6736.056952026769, 6753.662316884185, 4667.13657973276, 4695.178343789823, 4699.600533954584, 4702.976485117552, 4703.435587729495, 4717.127331443694, 4751.596114330251, 4751.596114330251, 4751.738654374042, 4757.452892917358, 5317.973410132925, 5458.426865179178, 5458.890822183614, 5462.0, 5463.754131064112, 6523.836904077341, 6527.575707579087, 6527.836804079936, 6532.804015879531, 6533.542652014408, 1660.9584760380847, 1681.0586071799587, 1681.2602454919795, 1682.4700219431086, 1683.537027317473, 1113.2318700096907, 1123.7078359626391, 1128.0129886349443, 1128.7516746315798, 1143.1712008793559, 6256.874862502725, 6287.811365659038, 6290.308066112724, 6298.496120310364, 6308.968455157695, 6641.535092543572, 6737.3599508046655, 6779.254282028973, 6779.694913728894, 6785.321467853274, 6305.495560355161, 6325.5255855811, 6368.29948705642, 6378.170837791057, 6448.193975753031, 3646.033801042726, 3680.4847321375028, 3694.390425579803, 3698.352788262097, 3703.0187000444903, 6889.345512176335, 7048.330408611174, 7048.92951070493, 7055.576665400093, 7060.399866011447, 7023.22744498115, 7027.964860175666, 7030.261822508665, 7033.964830175919, 7037.331453512011, 7001.754938577125, 7032.1912980007955, 7036.085308909911, 7041.4718896082995, 7045.436365090781, 6691.899621505779, 6874.346937040973, 6878.071460352989, 6891.517593768388, 6891.586504809728, 6722.05891175226, 6747.595144291358, 6748.122744043243, 6750.561213521106, 6752.426043786137, 7044.225135235222, 7055.753048643272, 7056.54132481387, 7059.5058345915295, 7065.258147894603, 4598.540145985375, 4699.718016918996, 4723.7401942893475, 4747.0, 4747.667663263538, 6814.591124532544, 6817.420519255918, 6819.624920629413, 6825.624590647562, 6831.453483721191, 6585.045168450643, 6617.404433600999, 6625.436837868835, 6628.171478565179, 6634.734610615715, 6413.647249401328, 6612.23959244693, 6615.933840661532, 6616.908210145465, 6618.238902526271, 6493.642849643315, 6496.415322620798, 6499.415052645096, 6503.902441463287, 6504.934950650622, 6633.535652504278, 6645.734170633121, 6645.767398141042, 6651.767188148391, 6654.667266636552, 6276.905846412399, 6354.17395738551, 6477.093206950936, 6477.319881412383, 6493.707783149675], "unit": "ms", "name": "prototype.html", "value": 4978.286827319781}, {"lowerIsBetter": false, "replicates": [808.6765293882448, 885.225427750718, 897.8698088777128, 939.0984654731461, 976.6142373762359, 14846.554603361903, 15055.171965541891, 15089.62275943102, 15145.848541514582, 15168.696626067485, 13421.865781342183, 13464.394102265396, 13472.663183420394, 13494.932525337385, 13525.188488690685, 740.4335683202341, 754.803751024732, 884.8318819424321, 903.5933829776594, 904.1726819959729, 885.1193062902421, 887.2236792806294, 893.4371346051978, 893.580017391828, 893.9150780675858, 776.289694929142, 786.2648423723808, 923.6213152607432, 924.0205382294755, 929.1962452478625, 922.2667978956712, 934.0379409208526, 934.56075644447, 934.6401635370363, 935.036911980661, 684.4832151725416, 688.6212583079287, 688.9104416425832, 690.5131882023162, 691.022203581931, 14006.369713362821, 14066.507672231524, 14084.366203520764, 14138.646533836634, 14155.716885662228, 12280.201786883825, 13320.734530219614, 13734.793978090336, 13921.164730116125, 13945.0, 10950.233483656148, 11036.724081897937, 11172.385518796464, 11254.54981800727, 11384.260023098472, 3784.678302344304, 3885.747426417302, 3910.3156947534067, 3928.3321835287784, 3931.567527571965, 47807.52192478065, 48721.756391217816, 49191.0, 49513.0, 49776.75111624491, 9821.165200957856, 10907.479599508104, 10942.0, 10942.835857462145, 11066.28069175501, 18921.7161742574, 19073.427797166107, 19094.14076366567, 19126.808731912504, 19136.0, 7941.801454963672, 7967.402444816662, 8036.718714845069, 8041.8793718094275, 8166.591670416574, 4694.43666759991, 4715.22198837188, 4753.0, 4758.381410416624, 4836.903261934742, 3251.4797632378704, 3255.121117298316, 3265.053134590942, 3288.473844184919, 3293.143782616537, 549.5191707256151, 560.1989155507582, 563.5970281248926, 574.3854076138492, 578.5371702637873, 638.3552611862053, 687.7283473028126, 707.6391040569347, 708.5075872268724, 710.8755967705735, 8935.910640893639, 9070.455772653662, 9262.120098590623, 9320.263059932995, 9330.486823224788, 665.8135721997849, 722.0613202836291, 744.6090802328713, 753.8040109571551, 772.0966469231013, 3892.649661530476, 4262.9786851066165, 4390.012247244408, 4587.8623641290815, 4660.976695116503, 58762.41237587569, 60422.09366859501, 60451.0, 60559.39440605626, 64949.35050649433, 54108.72945635246, 54338.45661543334, 54587.18119228215, 55055.0, 55239.0, 48434.0, 48856.511434885906, 49554.504454954986, 49893.75053124711, 50189.24716129261, 8818.955905220433, 8963.59663815143, 9023.95488022582, 9115.27077833785, 9202.677906273248, 34471.138221544665, 34975.0, 35128.47307290444, 35410.11474713153, 35541.28917421689, 42007.57992420036, 44254.55745442504, 46375.76812115918, 46925.06149876915, 47347.52652473569, 9566.13904748576, 11284.9435752824, 11323.943380283046, 11340.0, 11410.14423918193, 11088.5564577416, 11173.776524469627, 11292.378919159197, 11305.830412543653, 11369.374684392107, 11688.70778230551, 12007.579734709243, 12128.696782580506, 12371.443285052374, 12431.564895228623, 11071.833922491009, 11116.0, 11281.379524126252, 11375.20373573842, 11933.462994165457, 10468.424236667057, 12037.638870833889, 12141.210821296587, 12362.567310144103, 12674.936625316814, 10273.434961077213, 10347.327423717434, 10426.374417534973, 10805.243632945618, 10816.837747433952, 9930.0, 10357.4303413313, 10423.21825863048, 10469.842952355873, 10615.0], "unit": "ms", "name": "yui.html", "value": 6673.00450032597}], "name": "dromaeo_css", "value": 7309.74630342401}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 109.0, 104.0, 109.0, 109.0, 111.0, 101.0, 110.0, 109.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [145.0, 121.0, 166.0, 168.0, 123.0, 150.0, 157.0, 124.0, 161.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 161.0, 161.0, 160.0, 162.0, 159.0, 163.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 72.0, 157.0, 72.0, 71.0, 72.0, 71.0, 146.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 136.0, 92.0, 93.0, 93.0, 135.0, 97.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 108.0, 109.0, 110.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [59.0, 56.0, 57.0, 55.0, 58.0, 55.0, 57.0, 57.0, 58.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.8}, {"lowerIsBetter": true, "replicates": [77.0, 86.0, 78.0, 79.0, 78.0, 78.0, 78.0, 86.0, 79.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.6}, {"lowerIsBetter": true, "replicates": [116.0, 110.0, 108.0, 111.0, 113.0, 116.0, 126.0, 127.0, 114.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.9}, {"lowerIsBetter": true, "replicates": [143.0, 138.0, 144.0, 138.0, 140.0, 141.0, 141.0, 140.0, 140.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.4}, {"lowerIsBetter": true, "replicates": [67.0, 63.0, 63.0, 63.0, 63.0, 64.0, 70.0, 65.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.5}], "name": "kraken", "value": 1478.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.81339150864238], "unit": "score", "name": "Richards", "value": 210.36461799999995}, {"lowerIsBetter": false, "replicates": [167.25204883759827], "unit": "score", "name": "DeltaBlue", "value": 395.31952199999984}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [925.925925925926], "unit": "score", "name": "RayTrace", "value": 799.1881199999998}, {"lowerIsBetter": false, "replicates": [539.6654074473827], "unit": "score", "name": "Earley", "value": 1234.9559390000002}, {"lowerIsBetter": false, "replicates": [10479.166666666666], "unit": "score", "name": "Boyer", "value": 63.59885487077532}, {"lowerIsBetter": false, "replicates": [24333.333333333332], "unit": "score", "name": "RegExp", "value": 37.437739726027424}, {"lowerIsBetter": false, "replicates": [514.40329218107], "unit": "score", "name": "Splay", "value": 158.41850399999996}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23386.681310119584}]} 16:52:28 INFO - RETURN: dromaeo_css: 7309.75 16:52:28 INFO - RETURN: kraken: 1478.00 16:52:28 INFO - RETURN: v8_7: 23386.68 16:52:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23386.68"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1478.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7309.75"}}} 16:52:28 INFO - Return code: 0 16:52:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:52:28 INFO - # TBPL SUCCESS # 16:52:28 INFO - Running post-action listener: _resource_record_post_action 16:52:28 INFO - Running post-run listener: _resource_record_post_run 16:52:30 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 0; Write bytes: 521367552; Read time: 0; Write time: 414576 16:52:30 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 256516096; Read time: 0; Write time: 280120 16:52:30 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 0; Write bytes: 264851456; Read time: 0; Write time: 134456 16:52:30 INFO - Running post-run listener: _upload_blobber_files 16:52:30 INFO - Blob upload gear active. 16:52:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:52:30 INFO - Copying logs to upload dir... 16:52:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.860128 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-25 16:52:29.564050) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 16:52:29.568115) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010281 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456437208/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 16:52:29.623327) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:52:29.624067) ========= 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=41bf3e9aed84707956354da8000001c5-1456446695.504179-1665900026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004569 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 16:52:29.676483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 16:52:29.676833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 16:52:29.677145) ========= ========= Total master_lag: 0.28 =========