builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-044 starttime: 1455063125.71 results: success (0) buildid: 20160209134616 builduid: 8ee8407260034ff4a662a4988ca5836b revision: 6c778ebb6354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.706187) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.706817) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.707149) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004840 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.755306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.755803) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.787109) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.787942) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.038427 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.839915) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.840260) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.840691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:05.841012) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-09 16:12:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2016-02-09 16:12:06 (37.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171464 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.031613) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.031938) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.065209 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.108300) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.108715) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6c778ebb6354 --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 6c778ebb6354 --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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-09 16:12:06,169 Setting DEBUG logging. 2016-02-09 16:12:06,169 attempt 1/10 2016-02-09 16:12:06,169 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6c778ebb6354?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-09 16:12:06,427 unpacking tar archive at: mozilla-beta-6c778ebb6354/testing/mozharness/ program finished with exit code 0 elapsedTime=0.545964 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.668988) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.669691) ========= echo 6c778ebb6354 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'6c778ebb6354'] 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 6c778ebb6354 program finished with exit code 0 elapsedTime=0.003871 script_repo_revision: '6c778ebb6354' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.722666) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.723158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-09 16:12:06.734202) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-09 16:12:06.734577) ========= /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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:06 INFO - MultiFileLogger online at 20160209 16:12:06 in /builds/slave/test-pgo 16:12:06 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:12:06 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:12:06 INFO - {'append_to_log': False, 16:12:06 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:12:06 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:12:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:12:06 INFO - 'branch': 'Mozilla-Beta', 16:12:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:06 INFO - 'config_files': ('talos/linux_config.py',), 16:12:06 INFO - 'default_actions': ('clobber', 16:12:06 INFO - 'read-buildbot-config', 16:12:06 INFO - 'download-and-extract', 16:12:06 INFO - 'populate-webroot', 16:12:06 INFO - 'create-virtualenv', 16:12:06 INFO - 'install', 16:12:06 INFO - 'run-tests'), 16:12:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:06 INFO - 'download_minidump_stackwalk': True, 16:12:06 INFO - 'download_symbols': 'ondemand', 16:12:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:06 INFO - 'installer_path': 'installer.exe', 16:12:06 INFO - 'log_level': 'info', 16:12:06 INFO - 'log_name': 'talos', 16:12:06 INFO - 'log_to_console': True, 16:12:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:06 INFO - 'opt_config_files': (), 16:12:06 INFO - 'pip_index': False, 16:12:06 INFO - 'sps_profile': False, 16:12:06 INFO - 'sps_profile_interval': 0, 16:12:06 INFO - 'suite': 'dromaeojs', 16:12:06 INFO - 'system_bits': '32', 16:12:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:06 INFO - 'title': 'talos-linux64-ix-044', 16:12:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:06 INFO - 'use_talos_json': True, 16:12:06 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:12:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:06 INFO - 'work_dir': 'build'} 16:12:06 INFO - ##### 16:12:06 INFO - ##### Running clobber step. 16:12:06 INFO - ##### 16:12:06 INFO - Running pre-action listener: _resource_record_pre_action 16:12:06 INFO - Running main action method: clobber 16:12:06 INFO - rmtree: /builds/slave/test-pgo/build 16:12:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:12:12 INFO - Running post-action listener: _resource_record_post_action 16:12:12 INFO - ##### 16:12:12 INFO - ##### Running read-buildbot-config step. 16:12:12 INFO - ##### 16:12:12 INFO - Running pre-action listener: _resource_record_pre_action 16:12:12 INFO - Running main action method: read_buildbot_config 16:12:12 INFO - Using buildbot properties: 16:12:12 INFO - { 16:12:12 INFO - "properties": { 16:12:12 INFO - "buildnumber": 111, 16:12:12 INFO - "product": "firefox", 16:12:12 INFO - "script_repo_revision": "production", 16:12:12 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:12:12 INFO - "repository": "", 16:12:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:12:12 INFO - "buildid": "20160209134616", 16:12:12 INFO - "slavename": "talos-linux64-ix-044", 16:12:12 INFO - "pgo_build": "True", 16:12:12 INFO - "basedir": "/builds/slave/test-pgo", 16:12:12 INFO - "project": "", 16:12:12 INFO - "platform": "ubuntu64_hw", 16:12:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:12:12 INFO - "slavebuilddir": "test", 16:12:12 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:12:12 INFO - "branch": "mozilla-beta", 16:12:12 INFO - "repo_path": "releases/mozilla-beta", 16:12:12 INFO - "stage_platform": "linux64-pgo", 16:12:12 INFO - "builduid": "8ee8407260034ff4a662a4988ca5836b", 16:12:12 INFO - "revision": "6c778ebb6354" 16:12:12 INFO - }, 16:12:12 INFO - "sourcestamp": { 16:12:12 INFO - "repository": "", 16:12:12 INFO - "hasPatch": false, 16:12:12 INFO - "project": "", 16:12:12 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:12:12 INFO - "changes": [ 16:12:12 INFO - { 16:12:12 INFO - "category": null, 16:12:12 INFO - "files": [ 16:12:12 INFO - { 16:12:12 INFO - "url": null, 16:12:12 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2" 16:12:12 INFO - } 16:12:12 INFO - ], 16:12:12 INFO - "repository": "", 16:12:12 INFO - "rev": "6c778ebb6354", 16:12:12 INFO - "who": "sendchange", 16:12:12 INFO - "when": 1455063077, 16:12:12 INFO - "number": 7584793, 16:12:12 INFO - "comments": "Bug 1233238 - Compile with play-services-{ads,analytics,appindexing} to support Adjust SDK. r=nalexander a=lizzard", 16:12:12 INFO - "project": "", 16:12:12 INFO - "at": "Tue 09 Feb 2016 16:11:17", 16:12:12 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:12:12 INFO - "revlink": "", 16:12:12 INFO - "properties": [ 16:12:12 INFO - [ 16:12:12 INFO - "buildid", 16:12:12 INFO - "20160209134616", 16:12:12 INFO - "Change" 16:12:12 INFO - ], 16:12:12 INFO - [ 16:12:12 INFO - "builduid", 16:12:12 INFO - "8ee8407260034ff4a662a4988ca5836b", 16:12:12 INFO - "Change" 16:12:12 INFO - ], 16:12:12 INFO - [ 16:12:12 INFO - "pgo_build", 16:12:12 INFO - "True", 16:12:12 INFO - "Change" 16:12:12 INFO - ] 16:12:12 INFO - ], 16:12:12 INFO - "revision": "6c778ebb6354" 16:12:12 INFO - } 16:12:12 INFO - ], 16:12:12 INFO - "revision": "6c778ebb6354" 16:12:12 INFO - } 16:12:12 INFO - } 16:12:12 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2. 16:12:12 INFO - Running post-action listener: _resource_record_post_action 16:12:12 INFO - ##### 16:12:12 INFO - ##### Running download-and-extract step. 16:12:12 INFO - ##### 16:12:12 INFO - Running pre-action listener: _resource_record_pre_action 16:12:12 INFO - Running main action method: download_and_extract 16:12:12 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:12:12 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:12:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/test_packages.json 16:12:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:12:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:12:12 INFO - Downloaded 1270 bytes. 16:12:12 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:12:12 INFO - Using the following test package requirements: 16:12:12 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:12:12 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:12 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'jsshell-linux-x86_64.zip'], 16:12:12 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:12 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:12:12 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 16:12:12 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 16:12:12 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:12 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:12:12 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:12:12 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:12 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:12 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:12:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:12:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:12 INFO - Downloaded 21581697 bytes. 16:12:12 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:12:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:12:12 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:12:13 INFO - Return code: 0 16:12:13 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:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:12:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:14 INFO - Downloaded 21581697 bytes. 16:12:14 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:12:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:12:14 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:12:15 INFO - Return code: 0 16:12:15 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:12:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:12:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:15 INFO - Downloaded 11132038 bytes. 16:12:15 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:12:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:12:15 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:12:16 INFO - Return code: 0 16:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:12:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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:12:19 INFO - Downloaded 57942694 bytes. 16:12:19 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:12:19 INFO - mkdir: /builds/slave/test-pgo/properties 16:12:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:12:19 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:12:19 INFO - Contents: 16:12:19 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:12:19 INFO - Running post-action listener: _resource_record_post_action 16:12:19 INFO - Running post-action listener: set_extra_try_arguments 16:12:19 INFO - ##### 16:12:19 INFO - ##### Running populate-webroot step. 16:12:19 INFO - ##### 16:12:19 INFO - Running pre-action listener: _resource_record_pre_action 16:12:19 INFO - Running main action method: populate_webroot 16:12:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:19 INFO - '--tppagecycles', 16:12:19 INFO - '7'], 16:12:19 INFO - 'tests': ['tsvgm']}}, 16:12:19 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:12:19 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:19 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:12:19 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:12:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'tests': ['damp', 'tps']}, 16:12:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['damp', 'tps']}, 16:12:19 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:12:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:19 INFO - 'other': {'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'other_l64': {'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'other_nol64': {'tests': ['a11yr', 16:12:19 INFO - 'ts_paint', 16:12:19 INFO - 'tpaint', 16:12:19 INFO - 'sessionrestore', 16:12:19 INFO - 'sessionrestore_no_auto_restore']}, 16:12:19 INFO - 'svgr': {'tests': ['tsvgx', 16:12:19 INFO - 'tsvgr_opacity', 16:12:19 INFO - 'tart', 16:12:19 INFO - 'tscrollx', 16:12:19 INFO - 'cart']}, 16:12:19 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['tsvgx', 16:12:19 INFO - 'tsvgr_opacity', 16:12:19 INFO - 'tart', 16:12:19 INFO - 'tscrollx', 16:12:19 INFO - 'cart']}, 16:12:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'tests': ['tp5o']}, 16:12:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'talos_options': ['--e10s'], 16:12:19 INFO - 'tests': ['tp5o']}, 16:12:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'talos_options': ['--xperf_path', 16:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:19 INFO - 'tests': ['tp5n']}, 16:12:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:19 INFO - 'talos_options': ['--e10s', 16:12:19 INFO - '--xperf_path', 16:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:19 INFO - 'tests': ['tp5n']}}, 16:12:19 INFO - 'talos.zip': {'path': '', 16:12:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:19 INFO - Running post-action listener: _resource_record_post_action 16:12:19 INFO - ##### 16:12:19 INFO - ##### Running create-virtualenv step. 16:12:19 INFO - ##### 16:12:19 INFO - Running pre-action listener: _resource_record_pre_action 16:12:19 INFO - Running main action method: create_virtualenv 16:12:19 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:12:19 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:12:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:12:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:19 INFO - Using real prefix '/usr' 16:12:19 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:12:20 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:22 INFO - Installing pip.................done. 16:12:22 INFO - Return code: 0 16:12:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:12:22 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:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:22 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:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:22 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:22 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:12:22 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:12:22 INFO - Using env: {'DISPLAY': ':0', 16:12:22 INFO - 'HOME': '/home/cltbld', 16:12:22 INFO - 'LANG': 'en_US.UTF-8', 16:12:22 INFO - 'LANGUAGE': 'en_US:en', 16:12:22 INFO - 'LOGNAME': 'cltbld', 16:12:22 INFO - 'MAIL': '/var/mail/cltbld', 16:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:22 INFO - 'MOZ_NO_REMOTE': '1', 16:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:22 INFO - 'NO_EM_RESTART': '1', 16:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:22 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:22 INFO - 'SHELL': '/bin/bash', 16:12:22 INFO - 'SHLVL': '1', 16:12:22 INFO - 'TERM': 'linux', 16:12:22 INFO - 'TMOUT': '86400', 16:12:22 INFO - 'USER': 'cltbld', 16:12:22 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:22 INFO - '_': '/tools/buildbot/bin/python'} 16:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:22 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:12:22 INFO - Cleaning up... 16:12:22 INFO - Return code: 0 16:12:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:12:22 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:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:22 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:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:22 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:22 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:12:22 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:12:22 INFO - Using env: {'DISPLAY': ':0', 16:12:22 INFO - 'HOME': '/home/cltbld', 16:12:22 INFO - 'LANG': 'en_US.UTF-8', 16:12:22 INFO - 'LANGUAGE': 'en_US:en', 16:12:22 INFO - 'LOGNAME': 'cltbld', 16:12:22 INFO - 'MAIL': '/var/mail/cltbld', 16:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:22 INFO - 'MOZ_NO_REMOTE': '1', 16:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:22 INFO - 'NO_EM_RESTART': '1', 16:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:22 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:22 INFO - 'SHELL': '/bin/bash', 16:12:22 INFO - 'SHLVL': '1', 16:12:22 INFO - 'TERM': 'linux', 16:12:22 INFO - 'TMOUT': '86400', 16:12:22 INFO - 'USER': 'cltbld', 16:12:22 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:22 INFO - '_': '/tools/buildbot/bin/python'} 16:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:23 INFO - Downloading/unpacking psutil>=0.7.1 16:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:23 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:12:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:24 INFO - Installing collected packages: psutil 16:12:24 INFO - Running setup.py install for psutil 16:12:25 INFO - building 'psutil._psutil_linux' extension 16:12:25 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:12:25 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:12:25 INFO - building 'psutil._psutil_posix' extension 16:12:25 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:12:26 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:12:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:26 INFO - Successfully installed psutil 16:12:26 INFO - Cleaning up... 16:12:26 INFO - Return code: 0 16:12:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:12:26 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:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:26 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:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:26 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:26 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:12:26 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:12:26 INFO - Using env: {'DISPLAY': ':0', 16:12:26 INFO - 'HOME': '/home/cltbld', 16:12:26 INFO - 'LANG': 'en_US.UTF-8', 16:12:26 INFO - 'LANGUAGE': 'en_US:en', 16:12:26 INFO - 'LOGNAME': 'cltbld', 16:12:26 INFO - 'MAIL': '/var/mail/cltbld', 16:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:26 INFO - 'MOZ_NO_REMOTE': '1', 16:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:26 INFO - 'NO_EM_RESTART': '1', 16:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:26 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:26 INFO - 'SHELL': '/bin/bash', 16:12:26 INFO - 'SHLVL': '1', 16:12:26 INFO - 'TERM': 'linux', 16:12:26 INFO - 'TMOUT': '86400', 16:12:26 INFO - 'USER': 'cltbld', 16:12:26 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:26 INFO - '_': '/tools/buildbot/bin/python'} 16:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:12:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:27 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:12:27 INFO - Installing collected packages: mozsystemmonitor 16:12:27 INFO - Running setup.py install for mozsystemmonitor 16:12:27 INFO - Successfully installed mozsystemmonitor 16:12:27 INFO - Cleaning up... 16:12:27 INFO - Return code: 0 16:12:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:12:27 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:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:27 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:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:27 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:27 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:12:27 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:12:27 INFO - Using env: {'DISPLAY': ':0', 16:12:27 INFO - 'HOME': '/home/cltbld', 16:12:27 INFO - 'LANG': 'en_US.UTF-8', 16:12:27 INFO - 'LANGUAGE': 'en_US:en', 16:12:27 INFO - 'LOGNAME': 'cltbld', 16:12:27 INFO - 'MAIL': '/var/mail/cltbld', 16:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:27 INFO - 'MOZ_NO_REMOTE': '1', 16:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:27 INFO - 'NO_EM_RESTART': '1', 16:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:27 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:27 INFO - 'SHELL': '/bin/bash', 16:12:27 INFO - 'SHLVL': '1', 16:12:27 INFO - 'TERM': 'linux', 16:12:27 INFO - 'TMOUT': '86400', 16:12:27 INFO - 'USER': 'cltbld', 16:12:27 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:27 INFO - '_': '/tools/buildbot/bin/python'} 16:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:27 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:27 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:12:27 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:12:27 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:12:27 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:12:27 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:12:27 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:12:29 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:29 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:12:29 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:12:29 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:12:29 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:12:29 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:12:29 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:12:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:12:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:30 INFO - Downloading docopt-0.6.1.tar.gz 16:12:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:30 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:30 INFO - Running setup.py install for blobuploader 16:12:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:30 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:12:30 INFO - Running setup.py install for requests 16:12:30 INFO - Running setup.py install for docopt 16:12:30 INFO - Successfully installed blobuploader requests docopt 16:12:30 INFO - Cleaning up... 16:12:30 INFO - Return code: 0 16:12:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:12:30 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:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:30 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:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:30 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:30 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:12:30 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:12:30 INFO - Using env: {'DISPLAY': ':0', 16:12:30 INFO - 'HOME': '/home/cltbld', 16:12:30 INFO - 'LANG': 'en_US.UTF-8', 16:12:30 INFO - 'LANGUAGE': 'en_US:en', 16:12:30 INFO - 'LOGNAME': 'cltbld', 16:12:30 INFO - 'MAIL': '/var/mail/cltbld', 16:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:30 INFO - 'MOZ_NO_REMOTE': '1', 16:12:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:30 INFO - 'NO_EM_RESTART': '1', 16:12:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:30 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:30 INFO - 'SHELL': '/bin/bash', 16:12:30 INFO - 'SHLVL': '1', 16:12:30 INFO - 'TERM': 'linux', 16:12:30 INFO - 'TMOUT': '86400', 16:12:30 INFO - 'USER': 'cltbld', 16:12:30 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:30 INFO - '_': '/tools/buildbot/bin/python'} 16:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:12:31 INFO - Running setup.py (path:/tmp/pip-dtT1oe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:12:31 INFO - Running setup.py (path:/tmp/pip-RdeAs0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:12:31 INFO - Running setup.py (path:/tmp/pip-48_B_N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:12:31 INFO - Running setup.py (path:/tmp/pip-DYhntz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:12:31 INFO - Running setup.py (path:/tmp/pip-S90g_7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:12:31 INFO - Running setup.py (path:/tmp/pip-QZZBtE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:12:31 INFO - Running setup.py (path:/tmp/pip-yBmCwj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:12:31 INFO - Running setup.py (path:/tmp/pip-ie30d5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:12:31 INFO - Running setup.py (path:/tmp/pip-p917hI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:12:31 INFO - Running setup.py (path:/tmp/pip-TugCwi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:12:31 INFO - Running setup.py (path:/tmp/pip-XFSG0v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:12:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:12:31 INFO - Running setup.py (path:/tmp/pip-TZa94I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:12:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:12:32 INFO - Running setup.py (path:/tmp/pip-B0kHC9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:12:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:12:32 INFO - Running setup.py (path:/tmp/pip-WcdtAP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:12:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:12:32 INFO - Running setup.py (path:/tmp/pip-K1kF4p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:12:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:12:32 INFO - Running setup.py (path:/tmp/pip-LBzPT3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:12:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:12:32 INFO - Running setup.py (path:/tmp/pip-uSKi65-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:12:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:32 INFO - Running setup.py install for manifestparser 16:12:32 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:12:32 INFO - Running setup.py install for mozcrash 16:12:32 INFO - Running setup.py install for mozdebug 16:12:32 INFO - Running setup.py install for mozdevice 16:12:32 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:12:32 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:12:32 INFO - Running setup.py install for mozfile 16:12:32 INFO - Running setup.py install for mozhttpd 16:12:32 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozinfo 16:12:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozInstall 16:12:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozleak 16:12:33 INFO - Running setup.py install for mozlog 16:12:33 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for moznetwork 16:12:33 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozprocess 16:12:33 INFO - Running setup.py install for mozprofile 16:12:33 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozrunner 16:12:33 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:12:33 INFO - Running setup.py install for mozscreenshot 16:12:33 INFO - Running setup.py install for moztest 16:12:34 INFO - Running setup.py install for mozversion 16:12:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:12:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:34 INFO - Cleaning up... 16:12:34 INFO - Return code: 0 16:12:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:12:34 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:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:34 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:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:34 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:34 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:12:34 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:12:34 INFO - Using env: {'DISPLAY': ':0', 16:12:34 INFO - 'HOME': '/home/cltbld', 16:12:34 INFO - 'LANG': 'en_US.UTF-8', 16:12:34 INFO - 'LANGUAGE': 'en_US:en', 16:12:34 INFO - 'LOGNAME': 'cltbld', 16:12:34 INFO - 'MAIL': '/var/mail/cltbld', 16:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:34 INFO - 'MOZ_NO_REMOTE': '1', 16:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:34 INFO - 'NO_EM_RESTART': '1', 16:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:34 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:34 INFO - 'SHELL': '/bin/bash', 16:12:34 INFO - 'SHLVL': '1', 16:12:34 INFO - 'TERM': 'linux', 16:12:34 INFO - 'TMOUT': '86400', 16:12:34 INFO - 'USER': 'cltbld', 16:12:34 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:34 INFO - '_': '/tools/buildbot/bin/python'} 16:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:12:34 INFO - Running setup.py (path:/tmp/pip-nALq3J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:12:34 INFO - Running setup.py (path:/tmp/pip-mUmnj6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:12:34 INFO - Running setup.py (path:/tmp/pip-JN39s0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:12:34 INFO - Running setup.py (path:/tmp/pip-TqXuej-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:12:34 INFO - Running setup.py (path:/tmp/pip-IDTsMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:12:34 INFO - Running setup.py (path:/tmp/pip-yrALtY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:12:34 INFO - Running setup.py (path:/tmp/pip-Mkjh8R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:12:34 INFO - Running setup.py (path:/tmp/pip-WvAidL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:12:34 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:12:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:12:34 INFO - Running setup.py (path:/tmp/pip-QI0DiJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:12:35 INFO - Running setup.py (path:/tmp/pip-aVDlE4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:12:35 INFO - Running setup.py (path:/tmp/pip-bq2YyG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:12:35 INFO - Running setup.py (path:/tmp/pip-75qEq6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:12:35 INFO - Running setup.py (path:/tmp/pip-D4Yq9v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:12:35 INFO - Running setup.py (path:/tmp/pip-UUB2vK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:12:35 INFO - Running setup.py (path:/tmp/pip-eAdpjp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:12:35 INFO - Running setup.py (path:/tmp/pip-3uvrgQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:12:35 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:12:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:12:35 INFO - Running setup.py (path:/tmp/pip-Rywlxk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:12:35 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:12:35 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:12:35 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:12:35 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:12:35 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:12:35 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:12:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:35 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:12:35 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:12:35 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:12:35 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:12:35 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:12:35 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:12:37 INFO - Downloading blessings-1.6.tar.gz 16:12:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:37 INFO - Installing collected packages: blessings 16:12:37 INFO - Running setup.py install for blessings 16:12:37 INFO - Successfully installed blessings 16:12:37 INFO - Cleaning up... 16:12:37 INFO - Return code: 0 16:12:37 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:12:37 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:12:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:12:37 INFO - Reading from file tmpfile_stdout 16:12:37 INFO - Current package versions: 16:12:37 INFO - argparse == 1.2.1 16:12:37 INFO - blessings == 1.6 16:12:37 INFO - blobuploader == 1.2.4 16:12:37 INFO - docopt == 0.6.1 16:12:37 INFO - manifestparser == 1.1 16:12:37 INFO - mozInstall == 1.12 16:12:37 INFO - mozcrash == 0.16 16:12:37 INFO - mozdebug == 0.1 16:12:37 INFO - mozdevice == 0.47 16:12:37 INFO - mozfile == 1.2 16:12:37 INFO - mozhttpd == 0.7 16:12:37 INFO - mozinfo == 0.9 16:12:37 INFO - mozleak == 0.1 16:12:37 INFO - mozlog == 3.1 16:12:37 INFO - moznetwork == 0.27 16:12:37 INFO - mozprocess == 0.22 16:12:37 INFO - mozprofile == 0.27 16:12:37 INFO - mozrunner == 6.11 16:12:37 INFO - mozscreenshot == 0.1 16:12:37 INFO - mozsystemmonitor == 0.0 16:12:37 INFO - moztest == 0.7 16:12:37 INFO - mozversion == 1.4 16:12:37 INFO - psutil == 3.1.1 16:12:37 INFO - requests == 1.2.3 16:12:37 INFO - wsgiref == 0.1.2 16:12:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:37 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:37 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:12:37 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:12:37 INFO - Using env: {'DISPLAY': ':0', 16:12:37 INFO - 'HOME': '/home/cltbld', 16:12:37 INFO - 'LANG': 'en_US.UTF-8', 16:12:37 INFO - 'LANGUAGE': 'en_US:en', 16:12:37 INFO - 'LOGNAME': 'cltbld', 16:12:37 INFO - 'MAIL': '/var/mail/cltbld', 16:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:37 INFO - 'MOZ_NO_REMOTE': '1', 16:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:37 INFO - 'NO_EM_RESTART': '1', 16:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:37 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:37 INFO - 'SHELL': '/bin/bash', 16:12:37 INFO - 'SHLVL': '1', 16:12:37 INFO - 'TERM': 'linux', 16:12:37 INFO - 'TMOUT': '86400', 16:12:37 INFO - 'USER': 'cltbld', 16:12:37 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:37 INFO - '_': '/tools/buildbot/bin/python'} 16:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 INFO - Cleaning up... 16:12:38 INFO - Return code: 0 16:12:38 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:12: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:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:38 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:38 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:12:38 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:12:38 INFO - Using env: {'DISPLAY': ':0', 16:12:38 INFO - 'HOME': '/home/cltbld', 16:12:38 INFO - 'LANG': 'en_US.UTF-8', 16:12:38 INFO - 'LANGUAGE': 'en_US:en', 16:12:38 INFO - 'LOGNAME': 'cltbld', 16:12:38 INFO - 'MAIL': '/var/mail/cltbld', 16:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:38 INFO - 'MOZ_NO_REMOTE': '1', 16:12:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:38 INFO - 'NO_EM_RESTART': '1', 16:12:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:38 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:38 INFO - 'SHELL': '/bin/bash', 16:12:38 INFO - 'SHLVL': '1', 16:12:38 INFO - 'TERM': 'linux', 16:12:38 INFO - 'TMOUT': '86400', 16:12:38 INFO - 'USER': 'cltbld', 16:12:38 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:38 INFO - '_': '/tools/buildbot/bin/python'} 16:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:38 INFO - Downloading/unpacking jsonschema 16:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:38 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:12:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:40 INFO - Downloading/unpacking functools32 (from jsonschema) 16:12:40 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:12:40 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:12:40 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:12:40 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:12:40 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:12:40 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:12:40 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:40 INFO - warning: no files found matching '*.txt' 16:12:40 INFO - no previously-included directories found matching 'build' 16:12:40 INFO - no previously-included directories found matching 'dist' 16:12:40 INFO - no previously-included directories found matching '.git*' 16:12:40 INFO - Installing collected packages: jsonschema, functools32 16:12:40 INFO - Running setup.py install for functools32 16:12:40 INFO - warning: no files found matching '*.txt' 16:12:40 INFO - no previously-included directories found matching 'build' 16:12:40 INFO - no previously-included directories found matching 'dist' 16:12:40 INFO - no previously-included directories found matching '.git*' 16:12:40 INFO - Successfully installed jsonschema functools32 16:12:40 INFO - Cleaning up... 16:12:40 INFO - Return code: 0 16:12:40 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:40 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 0x7fa2e3d14f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29528a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a1f600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, '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': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', '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:12:40 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:12:40 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:12:40 INFO - Using env: {'DISPLAY': ':0', 16:12:40 INFO - 'HOME': '/home/cltbld', 16:12:40 INFO - 'LANG': 'en_US.UTF-8', 16:12:40 INFO - 'LANGUAGE': 'en_US:en', 16:12:40 INFO - 'LOGNAME': 'cltbld', 16:12:40 INFO - 'MAIL': '/var/mail/cltbld', 16:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:40 INFO - 'MOZ_NO_REMOTE': '1', 16:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:40 INFO - 'NO_EM_RESTART': '1', 16:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:40 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:40 INFO - 'SHELL': '/bin/bash', 16:12:40 INFO - 'SHLVL': '1', 16:12:40 INFO - 'TERM': 'linux', 16:12:40 INFO - 'TMOUT': '86400', 16:12:40 INFO - 'USER': 'cltbld', 16:12:40 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:40 INFO - '_': '/tools/buildbot/bin/python'} 16:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:40 INFO - Downloading/unpacking flake8 16:12:40 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:12:40 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:12:40 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:12:40 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:12:40 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:12:40 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:12:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:12:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:12:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:42 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:12:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:12:43 INFO - no previously-included directories found matching 'docs/_build' 16:12:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:12:43 INFO - Running setup.py install for pep8 16:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:12:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:12:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:12:43 INFO - no previously-included directories found matching 'docs/_build' 16:12:43 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:12:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:12:43 INFO - Cleaning up... 16:12:43 INFO - Return code: 0 16:12:43 INFO - Running post-action listener: _resource_record_post_action 16:12:43 INFO - Running post-action listener: _start_resource_monitoring 16:12:43 INFO - Starting resource monitoring. 16:12:43 INFO - ##### 16:12:43 INFO - ##### Running install step. 16:12:43 INFO - ##### 16:12:43 INFO - Running pre-action listener: _resource_record_pre_action 16:12:43 INFO - Running main action method: install 16:12:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:12:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:12:43 INFO - Reading from file tmpfile_stdout 16:12:43 INFO - Detecting whether we're running mozinstall >=1.0... 16:12:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:12:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:12:43 INFO - Reading from file tmpfile_stdout 16:12:43 INFO - Output received: 16:12:43 INFO - Usage: mozinstall [options] installer 16:12:43 INFO - Options: 16:12:43 INFO - -h, --help show this help message and exit 16:12:43 INFO - -d DEST, --destination=DEST 16:12:43 INFO - Directory to install application into. [default: 16:12:43 INFO - "/builds/slave/test-pgo"] 16:12:43 INFO - --app=APP Application being installed. [default: firefox] 16:12:43 INFO - mkdir: /builds/slave/test-pgo/build/application 16:12:43 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:12:43 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:12:58 INFO - Reading from file tmpfile_stdout 16:12:58 INFO - Output received: 16:12:58 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:12:58 INFO - Running post-action listener: _resource_record_post_action 16:12:58 INFO - ##### 16:12:58 INFO - ##### Running run-tests step. 16:12:58 INFO - ##### 16:12:58 INFO - Running pre-action listener: _resource_record_pre_action 16:12:58 INFO - Running main action method: run_tests 16:12:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:12:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:12:58 INFO - Python 2.7.3 16:12:58 INFO - Return code: 0 16:12:58 INFO - grabbing minidump binary from tooltool 16:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:58 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 0x2aae350>, '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 0x2aaf250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aaf6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:12:58 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:12:58 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:12:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:12:58 INFO - Return code: 0 16:12:58 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:12:58 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:12:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:12:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:12:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:12:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:12:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:12:59 INFO - Return code: 0 16:12:59 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/1455054376/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:12:59 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/1455054376/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-044 --webServer localhost 16:12:59 INFO - Using env: {'DISPLAY': ':0', 16:12:59 INFO - 'HOME': '/home/cltbld', 16:12:59 INFO - 'LANG': 'en_US.UTF-8', 16:12:59 INFO - 'LANGUAGE': 'en_US:en', 16:12:59 INFO - 'LOGNAME': 'cltbld', 16:12:59 INFO - 'MAIL': '/var/mail/cltbld', 16:12:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:12:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:59 INFO - 'MOZ_NO_REMOTE': '1', 16:12:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:59 INFO - 'NO_EM_RESTART': '1', 16:12:59 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:12:59 INFO - 'PWD': '/builds/slave/test-pgo', 16:12:59 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:12:59 INFO - 'SHELL': '/bin/bash', 16:12:59 INFO - 'SHLVL': '1', 16:12:59 INFO - 'TERM': 'linux', 16:12:59 INFO - 'TMOUT': '86400', 16:12:59 INFO - 'USER': 'cltbld', 16:12:59 INFO - 'XDG_SESSION_COOKIE': '574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569', 16:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:59 INFO - '_': '/tools/buildbot/bin/python'} 16:12:59 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/1455054376/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--webServer', 'localhost'] with output_timeout 3600 16:12:59 INFO - mozversion INFO | application_buildid: 20160209134616 16:12:59 INFO - mozversion INFO | application_changeset: 6c778ebb6354796d39d982842faea4eca0b88af5 16:12:59 INFO - mozversion INFO | application_display_name: Nightly 16:12:59 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:12:59 INFO - mozversion INFO | application_name: Firefox 16:12:59 INFO - mozversion INFO | application_remotingname: firefox 16:12:59 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:12:59 INFO - mozversion INFO | application_vendor: Mozilla 16:12:59 INFO - mozversion INFO | application_version: 45.0 16:12:59 INFO - mozversion INFO | platform_buildid: 20160209134616 16:12:59 INFO - mozversion INFO | platform_changeset: 6c778ebb6354796d39d982842faea4eca0b88af5 16:12:59 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:12:59 INFO - mozversion INFO | platform_version: 45.0 16:12:59 INFO - 2016-02-09 16:12:59,702 DEBUG : using testdate: 1455063179 16:12:59 INFO - 2016-02-09 16:12:59,702 DEBUG : actual date: 1455063179 16:12:59 INFO - 2016-02-09 16:12:59,749 INFO : starting webserver on 'localhost:42058' 16:12:59 INFO - 2016-02-09 16:12:59,751 INFO : Starting test suite talos-linux64-ix-044 16:12:59 INFO - 2016-02-09 16:12:59,751 INFO : Starting test dromaeo_css 16:12:59 INFO - 2016-02-09 16:12:59,751 DEBUG : operating with platform_type : linux_ 16:12:59 INFO - 2016-02-09 16:12:59,751 INFO : Initialising browser for dromaeo_css test... 16:12:59 INFO - 2016-02-09 16:12:59,767 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKBHLUn/profile http://localhost:42058/getInfo.html 16:13:05 INFO - 2016-02-09 16:13:05,372 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:13:05 INFO - 2016-02-09 16:13:05,372 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:13:05 INFO - 2016-02-09 16:13:05,372 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:13:05 INFO - 2016-02-09 16:13:05,373 DEBUG : BROWSER_OUTPUT: __metrics 16:13:05 INFO - 2016-02-09 16:13:05,622 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:13:06 INFO - 2016-02-09 16:13:06,650 INFO : Browser initialized. 16:13:06 INFO - 2016-02-09 16:13:06,651 INFO : Running cycle 1/1 for dromaeo_css test... 16:13:06 INFO - 2016-02-09 16:13:06,651 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKBHLUn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:13:18 INFO - 2016-02-09 16:13:18,282 DEBUG : BROWSER_OUTPUT: RSS: Main: 133992448 16:13:18 INFO - 2016-02-09 16:13:18,283 DEBUG : BROWSER_OUTPUT: 16:14:09 INFO - 2016-02-09 16:14:09,125 DEBUG : BROWSER_OUTPUT: 1455063249121 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:16:17 INFO - 2016-02-09 16:16:17,517 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42058/tests/dromaeo/cssquery-ext.html) 16:16:18 INFO - 2016-02-09 16:16:18,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 229654528 16:16:18 INFO - 2016-02-09 16:16:18,593 DEBUG : BROWSER_OUTPUT: 16:19:20 INFO - 2016-02-09 16:19:20,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-ext.html (next: http://localhost:42058/tests/dromaeo/cssquery-jquery.html) 16:19:21 INFO - 2016-02-09 16:19:21,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 204800000 16:19:21 INFO - 2016-02-09 16:19:21,185 DEBUG : BROWSER_OUTPUT: 16:22:22 INFO - 2016-02-09 16:22:22,938 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42058/tests/dromaeo/cssquery-mootools.html) 16:22:23 INFO - 2016-02-09 16:22:23,500 DEBUG : BROWSER_OUTPUT: RSS: Main: 198406144 16:22:23 INFO - 2016-02-09 16:22:23,500 DEBUG : BROWSER_OUTPUT: 16:25:25 INFO - 2016-02-09 16:25:25,204 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42058/tests/dromaeo/cssquery-prototype.html) 16:25:25 INFO - 2016-02-09 16:25:25,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 200069120 16:25:25 INFO - 2016-02-09 16:25:25,766 DEBUG : BROWSER_OUTPUT: 16:28:28 INFO - 2016-02-09 16:28:28,139 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42058/tests/dromaeo/cssquery-yui.html) 16:28:28 INFO - 2016-02-09 16:28:28,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 194859008 16:28:28 INFO - 2016-02-09 16:28:28,701 DEBUG : BROWSER_OUTPUT: 16:31:30 INFO - 2016-02-09 16:31:30,821 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/dromaeo/cssquery-yui.html (next: http://localhost:42058/tests/dromaeo/cssquery-dojo.html) 16:31:31 INFO - 2016-02-09 16:31:31,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 237318144 16:31:31 INFO - 2016-02-09 16:31:31,080 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,080 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:31:31 INFO - 2016-02-09 16:31:31,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:31:31 INFO - 2016-02-09 16:31:31,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:31:31 INFO - 2016-02-09 16:31:31,081 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:31:31 INFO - 2016-02-09 16:31:31,081 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3276.901692949212;3356.7985920844767;3381.7632765706394;3385.6952874241315;3410.982945085275;5555.166724991253;5561.304836895388;5583.07879199932;5583.664980101192;5592.07730724431;5542.279503664528;5570.916436253449;5588.329400471949;5591.2731344925105;5595.020871347519;4928.67963582366;5004.149294619905;5063.594912406998;5080.618953578478;5143.53708166265;5039.21892106724;5051.520105589963;5094.312267843849;5103.209002604603;5108.719020453892;4993.1262029144755;5041.773120209581;5081.1108056090225;5104.259882317062;5128.333316668846;5060.645754797166;5072.797088116471;5096.821611243608;5098.617603679739;5117.385913690344;4829.975850120727;4982.153033984231;4995.200767877159;5053.4946505349535;5059.114654935372;5218.634695571312;5254.133068043769;5307.495787900143;5314.070037743418;5336.626436149471;5955.464008239236;5962.165296858443;6005.069214271794;6013.338532761392;6023.488003519706;5696.857578560528;5753.108268218432;6024.126501657235;6034.456898879078;6037.335893051761;5421.430749771257;5478.123500239983;5481.260029895953;5511.751971161308;5512.283403157604;6105.84735381619;6111;6111.144439778434;6120.479759220427;6167.753289868355;5668.319801623831;5787.710614469344;5852.15143804146;5922.3485416604135;5926.8814623707285;6007.879842403127;6027.547933904974;6033.607815491979;6044.395560443921;6045.8488537786025;5944.108383742473;5949.0481522956115;5987.341392446828;5997.340292567814;6005.879882402328;5724.084146536534;5792.478676919013;5824.388439213906;5906.468417842329;5967.254093238345;4554.817807287672;4597.287420449768;4601.677882548256;4608.308753686973;4644.349791029259;4079.796010199478;4116.506019277646;4142.399352093929;4146.0464093258715;4162.771047592411;5537.501624853702;5560.332760068818;5618.831435056954;5689.544836413077;5714.314282286152;6004.249468816398;6009.399060093956;6018.247719035121;6023.548233882389;6064.575479716377;5460.6177567570685;5699.458551437607;5727.885442291131;5748.540116790647;5755.309362876482;5110.565601923804;5125.8718532036255;5164.122099243138;5184.7407629618365;5188.066148093304;6129.877402452015;6190.566660333733;6195.659238741912;6197.287311959182;6201.10084037812;5842.853928651818;5971.223740913653;6032.366601506778;6041.063635136561;6050.485708714633;5980.222571065734;6047.213862197887;6053.031514957585;6056;6084.90872636902;5903.203067585821;5929.85175370602;5947.553933455095;5988.311344195299;5990.7903223386975;5768.971155144365;5878.823635290948;5922.644641321534;6020.217371741821;6049.274087109575;5822.0684690449325;5823.301203855564;5823.330317013598;5825.825225243249;5858;5788.9131663024245;5803.680797556175;5807.361190268983;5808.94191058084;5816.3892791258;5519.944800551943;5572.972135139461;5708.914366284426;5736.0248757712125;5838.3869693683255;5806.4193580641595;5822.70886455667;5839.883202336015;5846.35690074083;5851.707414629166;5684.9431505684415;5717.914231286451;5721.256236689203;5724.627899186539;5725.599208055396 16:31:31 INFO - 2016-02-09 16:31:31,081 DEBUG : BROWSER_OUTPUT: |1;ext.html;594.8245267646043;624.5971348480227;637.9011253255744;639.5682914033026;640.3500447046249;33204.66795332046;33862.49206261905;42813.571864281344;43375.13249735011;43545.12909741803;46984.295235571495;47275.76362118193;47307.526924730744;47559.0488190236;47630.52369476304;8069;8166.918330816675;9094.090590940887;9252.583633736464;9266.721998340026;10923.781524369508;11072.723181920475;11128.777424451506;11158.721031974184;11165.83251251232;10788.136949044097;10845.728856778565;10851.240413171081;10855.294405863633;10862.945685271563;10796.784064318708;10887.891121088845;10892.836607450894;10895.073918716918;10902.127829773639;10641.893581064167;10767.83848242277;10785.245032847704;10785.514651840607;10790.298630588986;43620.78189609064;43686;43835;43933.78033109846;44102.33846492305;3359.6136444308972;3436.8453419596176;3477.7217822574385;3710.9794806428176;3753.887383378503;33245.50131748026;33264.83367583171;33271.16822079443;33496.83251583751;33904.83047584746;4140.358244472111;4169.259799552883;4211.263028969918;4278.251306021465;4347.7173983690955;459284;462620;463680;469890;471107;60843.69578152081;61903.38096618976;66550.33449665441;67304.32695673079;67313.66343168351;73222.26777732262;74294;74536.25463745401;74546;74561;14834.10995340283;15056.924715376572;15067.246637668073;15086.321115549827;15086.471973481095;12417.130800844036;12540.310282934344;12557.183783054072;12558.74882502345;12567.685807854878;21916.45208869759;22786.20248291335;22865;22957.426064348525;22975.88512057429;8416.747497575083;8601.35489838252;8657.177568131017;8692.348073894484;8742.213200811957;1031.2832581355885;1097.5170924793065;1130.8982191602795;1158.3571118029406;1175.8588969323737;29898.40203195924;30016.54975175374;30668.386632267677;30846;31018;1058.0636137018794;1066.2376400873413;1102.795982743195;1112.7996960547018;1230.772307123185;14568.708625827636;15216.695666086616;15429.459968901034;17526.386576470017;17806.91096544509;441130;444537;449143;449242;449346;59216.40783592195;59712;59759;59809;59820;1423.9145651260958;1426.023174125748;1433;1437.5615437291726;1441.7332793433247;31049.534256985713;31320.37359252848;31399.215019623778;31471.84264078665;32197.67802321947;49302.26046609232;49779;50202.74898625483;50220.24669630032;50221.74889125531;306472;311892;312313;312838;313877;30545.54181687232;31148;33914.66085339115;34189.31621367609;35029.29941401107;39664.80167599259;39980.40029399503;40164.79917600393;40579.1884162321;40726.59273407228;34206.315873682885;37277.81361093177;37420.62579374172;37786;45925.770371149265;39148.847542349395;39176.41235381414;39674.60325396709;40097.59902401055;40210;38777.61222387853;39120;39280.607193927695;39372;39408.80295598504;34169.31661366709;35144.297114057066;35159;36108.09729756672;36366.63633363633;26818.731812681624;27027.189184324503;27084.593731094443;27435;28154.37828153334 16:31:31 INFO - 2016-02-09 16:31:31,082 DEBUG : BROWSER_OUTPUT: |2;jquery.html;366.369843868546;367.8933109398275;369.3001761661651;370.27241470510444;374.10028880542296;15589.766153507706;17289.394871179513;17576.384826531073;18222.088895555236;18783.154758035886;18574.07129643516;18673.439796806084;18871.811281887178;19283.807161928376;19293.903530482363;5576.219329293912;5695.145728140791;5707.372189059221;5713.371529131813;5715.199872017921;4191.937120943188;4309.37514060461;5574.247476590669;6023.819285421422;6210.565260431772;6126.7855625053135;6140.754369825202;6157.692115394212;6168.105624684418;6203.441690247877;6748.493862960273;6954.3045695430055;6967.895481567755;6977.965110174468;6999.860002799941;6595.340465953414;6674.93325066748;6824.18109826819;6834.179898412172;6839.487038472134;24338;24384.512309753707;24606.630900536507;24710.13514526983;25870.223893283233;2428.1501474483966;2523.1547431610247;2591.183777110223;2636.0905487606747;2657.3356660834793;15042.623934401727;15656.295466704025;15699;15840.920795396065;16071.598210044725;2092.5082605587677;2111.862728922615;2144.1959265275523;2189.386971647941;2195.9560808783735;68689;71091;73013.634931825;73238;73463.63268183624;16304.184790760652;16835.242414091394;16855.410060647817;16866.156692165343;16882.324707011827;26790.732092678823;27042.729572704415;27160;27309.863450683017;27472;9411.576479058322;9526.90473095274;9542.045795420403;9584.66453674132;9608.471534065553;8043.678252869937;8068.757937261774;8068.9596552016865;8111.229433203836;8116.310113640288;8258.380621453294;8292.958535207285;8312.625931833081;8315.667373305121;8365.707200247962;2339.695839540883;2664.537697719667;3144.9371012579622;3167.0340546133652;3207.3905957867933;981.5950920245427;1002.5939494504737;1027.0138477690853;1042.2183362478142;1138.4307846076797;11644.708882278012;18166.5458363542;18169.36407225741;18289.634207316045;18924;482.4018217410456;483.64210484242113;491.6582974832457;500.2596157686644;503.43866588753275;6384.170057892537;6568.441682457044;6603.438707709803;6616.503762217853;6624.171978502687;620976;632241;705587;720258;721453;47705.76147119312;48623.27065094026;89004;90519;91570;503.6097024805805;512.7641285008863;514.5574805667198;515.6261710260031;516.0994065355978;10812.837807433052;10876.673699789018;11200.21598488098;11201.43992800373;11372.090232781524;36414.27171456609;36833.63166368409;38587.807060964515;40080;40091.39862902117;47171.52828471671;47259;47865.28202076902;48784.024319512704;50963.2355514675;9989.800203996025;10127.79744405093;10417;10432.269741118049;10432.478376081013;19036.714449282994;19201.51996200106;19279;19292.32476863303;19481.123349449594;14939.626509337353;15545.766813498032;16050.919745401197;16428.589285267484;16504.25730842091;15758.921205393899;16011.919940400223;16400.67198656044;16505;16862.915685421496;15634.29645665925;16070.35718571244;16308;16542.172891355145;16946.15269236558;10051.447170405405;10197.337173084023;10616.628418005334;10642.308249963728;10725.946370268412;16317.59206019859;16381.508554743377;16412.917935410245;16433.34266629369;16454.67090658204 16:31:31 INFO - 2016-02-09 16:31:31,082 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1573.2133933033483;1595.609075776434;1681.1930273468736;1732.4542769027764;1734.020278542623;7964.482308649941;8356.665733370673;8366.456180348274;8371.706990255343;8375.036870759865;7245.818854528627;7439.4792364534505;7820.3743700503965;8028.317593004587;8246.835063298731;2367.9052837886506;2383.4875501767115;2407.3018824540864;2407.5425669122897;2410.9758902411013;2125.192426877792;2411.6141417373224;2423.466837295792;2461.052494789501;2463.08865719684;2132.722746043012;2354.7645235476402;2403.387136280246;2410.156445244168;2410.276916924924;2370.099362242337;2399.9760002400017;2409.1929203716777;2410.807135429179;2411.0476361837063;2291.3126062181277;2334.976650233493;2338.75443078476;2377.239283429303;2378.4053986503372;8163;8228.25945664893;8230.958845205736;8258.710945116953;8262.049864265626;4808.447028591721;4884.5848102911295;4890.24201248807;4900.460949295574;4901.730404827733;3138.59198304219;3145.811251324928;3146.307812281317;3199.376121656278;3236.8057916525086;982.3761911186448;989.8119357322086;998.7902540466438;1004.5881188712666;1027.825269704152;11312.830307545393;11341.092712583057;11373.090152787758;11402.600908968147;11418.54325826966;4558.908821823545;4564.041551274203;4613.469451013109;4629.213033784265;4638.953610463852;9773.804523909483;9774.755631109138;9799.216062714966;9804.313698041211;9826.115649591571;5103.2855400244;5128.230765385222;5135.178371460548;5135.87160320995;5143.434222235551;4602.861914142581;4611.631069514431;4618.261078227468;4664.183767840615;4669.252919532859;3940.5862384449792;3967.0677390813134;3975.860844870416;3982.7411218270718;4027.81874815634;1143.1369316166408;1145.3643228008368;1190.1014733875868;1200.9099317551045;1247.4324182497123;1131.332513816852;1135.7274254178938;1143.308298479408;1327.5486334646266;1383.4466213514474;7842.5686587237105;7844.568548729761;7904.20957904205;7925.960370198112;7969.641366138421;1188.5127097889824;1190.4107466803987;1195.6950977500856;1200.2498438475952;1203.4043148641304;6195.16365290689;6239.469645080128;6265.185525881606;6274.874502509924;6320.241571011416;9178.357514973888;9425.2931030173;9438.90561094394;9469.621215151317;9486.098820612031;9349.111834375723;9369.016253293443;9370.95314523423;9390.436573805593;9399.812003759887;7635.083789945242;7839.725609603636;7842.45102842807;7842.803929901798;7851.136374998746;7853.37173026168;7861.567613781068;7938.5236885787035;7959.403044771781;8002.7199048033035;8898.332625053017;9107.362484626012;9114.954425227832;9149;9173.35786494939;9117.452952822852;9164.95417522908;9181.724548263563;9358.11097945694;9485.193758530602;7079.716811327489;7152.463565232732;7163.283671632795;7166.211716711262;7176.892346614701;7542.47202695806;7604.9733286005685;7627.466077374531;7629.351505122127;7640.0831900170115;6903.447724181953;7090.07828982229;7145.356917877416;7204.387627051759;7312.3053309937595;6899.132743186132;7437.851242975218;7454.291842274975;7492.737754178577;7580.8483830324185;7695.846083078419;7720.7683769487;7732;7742.612869356397;7743.99328087345;6730.32696730323;6750.72997080131;6759.053732477555;6761.087253220752;6891.965540172267;7064.717411303491;7078.929210708033;7116.715331386687;7118.323759242864;7123.109611298588 16:31:31 INFO - 2016-02-09 16:31:31,083 DEBUG : BROWSER_OUTPUT: |4;prototype.html;363.70721569136845;365.786015181119;366.57843479997985;368.8119059279768;373.07105307783627;6205.441510264075;6232.84417889553;6288.591241569295;6297.181366422359;6334.144890439789;5974.193483879675;6011.609245399046;6120.785772497963;6231;6259.561830671855;4836.226203807391;4841.8063277468855;4861.854144375674;4862.465128835825;4864.7567621618955;4674.883127921812;4862.465128835825;4863.124637565237;4867.975660121694;4869.8782530436665;4669.229577119771;4753.881152971168;4767.261074533453;4818.927716084261;4845.781939812717;4792.137415265251;4810.134175848346;4814.855554333376;4815.253635686439;4831.710097394132;4255.702100852942;4256.787160641987;4257.87226383209;4276.037891474424;4276.401303817468;5797.275340582427;5914.792982245601;5927.1405646181065;5929.169916211734;5933.643981361132;5660.122680984454;5666.943330566724;5672;5674.829755107354;5681.573881958829;6622.37087476689;6632.668366581652;6636.402723754885;6636.73453061877;6641.734330626769;4751.71489710615;4756.7859446325;4756.9286460703115;4758.333833263346;4764.8332308369045;6772.08576842125;6939.063226464413;6947.062146610244;6951.721931122749;6951.89572156418;4881.51184881509;4882.9755851221225;4884.169691152512;4887.218045112836;4889.119958407449;4938.481459446706;4949.109160351171;4951.257311403318;4955.529224723646;4960.255961605788;5402.945970540323;5608.355039170466;5629.352624448159;5639.548836093103;5639.915401268984;6722.630255336004;6725.831854203684;6726.679295642995;6727.192736871509;6729.596224226562;1688.0546893739545;1716.6995775739197;1719.9226034828462;1720.6386658801541;1721.3458885623384;1144.9771004579861;1146.140394703972;1151.8617765867982;1152.9711757206137;1178.2164860367932;6477.093206951031;6512.902306465407;6516;6517.4134327910715;6523.6738163092605;6648.401643852077;6940.479464040217;6943.027976083353;6944.826379340557;6946.756863509854;6372.076048972873;6390.968045159838;6510.121133646991;6551.672416379162;6554.573952693059;3700.40793473043;3754.3805272129794;3756.492873462102;3756.492873462102;3758.567764707094;6976.5814051157095;7166.784996450115;7174.246704096099;7184.676689548983;7192.496525243183;7161.749338773118;7168.175659799085;7175.784726458214;7177.246389129171;7180.31786980236;7175.282471752783;7178.138623365126;7181.784546463615;7184.928150718531;7187.784366469119;6850.349216824394;6948.166220053626;6959.582425054513;6959.826004349729;6977.092977913042;6858.245592984668;6863.450923925974;6867.381935625817;6873.931260687329;6874.690638921361;7143.642817858982;7145.249748776304;7149.356557909813;7149.428045756431;7157.141143062658;4656.324832899279;4717.150912835723;4748;4757.21505951528;4774.570288674036;6914.861702765816;6920.792376228721;6924.549904256207;6930.13373328334;6940.6529673515115;6749.595024298558;6751.257361690308;6757.662116894234;6765.221999470124;6769.153855768029;6573.967130164318;6725.630090345078;6740.831479213059;6746.257911629815;6758.898616520854;6632.635205063768;6632.801015969529;6647.401733843977;6650.534462587766;6651.201855777338;6743.966280168568;6768.796936091925;6769.153855768029;6778;6779.15260592426;6286.025666021774;6379.298277189413;6523.510736694861;6523.575967562093;6525.3148419417175 16:31:31 INFO - 2016-02-09 16:31:31,084 DEBUG : BROWSER_OUTPUT: |5;yui.html;838.3712215838121;883.982320353593;909.0818273543719;914.6341463414637;953.0374321934848;13777.242251676154;14165.92917035416;14167.291635418214;14204.644883877909;14222.43110275589;13007.154534955222;13071.150375225603;13073.934630326836;13079.803802942963;13089.541866034691;761.056290200152;774.8179177893219;912.6303846942;927.4620719982394;931.1154403316876;918.6830543462495;921.9400738951947;930.4556834251971;933.2021121940711;933.4819175357708;807.229096213118;955.4697143085584;967.4582233948976;967.6226271754022;967.9854802177973;808.9312408445289;945.0832692288503;962.1581116523007;966.120830044663;966.367029595609;714.2750108639735;717.4153065251803;718.2494293463343;718.8346680263569;719.363363423374;13305.600831975056;13359.599212023655;13407.46370145193;13410.731785364336;13411.597652070453;12407.689807754788;12456.065795065315;12651.683707907285;12977.091603587753;13381.799273010913;10516.263861529696;10633.46832658364;10719.571217151304;10961.56153753849;11269.718257043558;3775.565809931838;3796.0320118369914;3806.9428958565645;3821.102041020386;3829.3681542545446;43872.12255754867;44566;47156.292655610196;50704.23943640848;50719.492805072216;9935.65225217114;10098.899011009942;10461.320014199053;10499.317544359592;10555.155587552978;19076.904615476833;19307;19321;19370.322038728576;19372;7578.204288549733;7594.81012974669;7659.348955338859;7760;7869.055713314439;4264.680148988777;4415.911681766347;4423.203823311835;4553.203189441834;4617.215073437524;3200.0719791260276;3213.2609499815176;3213.9839300803346;3220.5169224616025;3221.291315910496;587.8677297608004;595.746807606774;602.1359349333633;609.7347653770624;616.5499185594451;617.9196704428485;682.2631557917438;687.4500399680336;748.1097493982144;748.6107224243362;8573.528455934984;9159;9261.398009129385;9406.529673516297;9460.95269523648;703.697410113656;715.3275920634587;722.5447967780265;729.7081167533029;757.23141011873;4270.487541494978;4355.455568053993;4384.079343337935;4408.6913916026215;4454.643628509712;58370.708146459845;62739;63115.6844215776;69270.30729692639;70122.29877701157;54547.72726136344;54622;54658.45341546613;55609.44390556042;57132;48402.999999999294;48983.265251021265;50633.493665063615;51308.23037654438;51563.22655160176;8744.606492707717;8812.691555795516;8863.689770857989;9099.31755118382;9103.135202155785;38280.234395312495;38305.425418618186;38662.80668596639;39771.40342894801;41775.16449670929;48945;49561;49685.50314496809;50814.42670159703;51079.48920510747;11563.132765042823;11752.412379381169;11938.22401543897;12153.513859445524;12455.813162802746;12794.744105118032;12898.548550800677;12920.353982301036;13689.315534223448;14437.927810360881;11840.763184736084;12088.697782555506;12250.571230006906;12365.938170309091;12594;11656.475458604553;11772.70568235801;12278.140530162802;12666.113372064236;12816.935915320364;11549.42252887369;11702.590409335633;11749.29504229749;11956.163068585463;11981.580644677395;10918.672439826818;11167.218294719292;11171.441427928408;11245.71885702864;11487.425628718698;9620.326577139813;9933.65232216869;9949.403035817873;9992.500374981368;10191.643292485023 16:31:31 INFO - 2016-02-09 16:31:31,084 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:31:31 INFO - 2016-02-09 16:31:31,084 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:31:31 INFO - 2016-02-09 16:31:31,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,759 16:31:31 INFO - 2016-02-09 16:31:31,084 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: __startTimestamp1455064291073__endTimestamp 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5527.93 Median:5725.60 stddev:608.95 (10.6%) stddev-sans-first:584.65 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: Values: 3276.9 3356.8 3381.8 3385.7 3411.0 5555.2 5561.3 5583.1 5583.7 5592.1 5542.3 5570.9 5588.3 5591.3 5595.0 4928.7 5004.1 5063.6 5080.6 5143.5 5039.2 5051.5 5094.3 5103.2 5108.7 4993.1 5041.8 5081.1 5104.3 5128.3 5060.6 5072.8 5096.8 5098.6 5117.4 4830.0 4982.2 4995.2 5053.5 5059.1 5218.6 5254.1 5307.5 5314.1 5336.6 5955.5 5962.2 6005.1 6013.3 6023.5 5696.9 5753.1 6024.1 6034.5 6037.3 5421.4 5478.1 5481.3 5511.8 5512.3 6105.8 6111.0 6111.1 6120.5 6167.8 5668.3 5787.7 5852.2 5922.3 5926.9 6007.9 6027.5 6033.6 6044.4 6045.8 5944.1 5949.0 5987.3 5997.3 6005.9 5724.1 5792.5 5824.4 5906.5 5967.3 4554.8 4597.3 4601.7 4608.3 4644.3 4079.8 4116.5 4142.4 4146.0 4162.8 5537.5 5560.3 5618.8 5689.5 5714.3 6004.2 6009.4 6018.2 6023.5 6064.6 5460.6 5699.5 5727.9 5748.5 5755.3 5110.6 5125.9 5164.1 5184.7 5188.1 6129.9 6190.6 6195.7 6197.3 6201.1 5842.9 5971.2 6032.4 6041.1 6050.5 5980.2 6047.2 6053.0 6056.0 6084.9 5903.2 5929.9 5947.6 5988.3 5990.8 5769.0 5878.8 5922.6 6020.2 6049.3 5822.1 5823.3 5823.3 5825.8 5858.0 5788.9 5803.7 5807.4 5808.9 5816.4 5519.9 5573.0 5708.9 5736.0 5838.4 5806.4 5822.7 5839.9 5846.4 5851.7 5684.9 5717.9 5721.3 5724.6 5725.6 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:58075.09 Median:19253.51 stddev:109258.82 (567.5%) stddev-sans-first:109480.08 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: Values: 594.8 624.6 637.9 639.6 640.4 33204.7 33862.5 42813.6 43375.1 43545.1 46984.3 47275.8 47307.5 47559.0 47630.5 8069.0 8166.9 9094.1 9252.6 9266.7 10923.8 11072.7 11128.8 11158.7 11165.8 10788.1 10845.7 10851.2 10855.3 10862.9 10796.8 10887.9 10892.8 10895.1 10902.1 10641.9 10767.8 10785.2 10785.5 10790.3 43620.8 43686.0 43835.0 43933.8 44102.3 3359.6 3436.8 3477.7 3711.0 3753.9 33245.5 33264.8 33271.2 33496.8 33904.8 4140.4 4169.3 4211.3 4278.3 4347.7 459284.0 462620.0 463680.0 469890.0 471107.0 60843.7 61903.4 66550.3 67304.3 67313.7 73222.3 74294.0 74536.3 74546.0 74561.0 14834.1 15056.9 15067.2 15086.3 15086.5 12417.1 12540.3 12557.2 12558.7 12567.7 21916.5 22786.2 22865.0 22957.4 22975.9 8416.7 8601.4 8657.2 8692.3 8742.2 1031.3 1097.5 1130.9 1158.4 1175.9 29898.4 30016.5 30668.4 30846.0 31018.0 1058.1 1066.2 1102.8 1112.8 1230.8 14568.7 15216.7 15429.5 17526.4 17806.9 441130.0 444537.0 449143.0 449242.0 449346.0 59216.4 59712.0 59759.0 59809.0 59820.0 1423.9 1426.0 1433.0 1437.6 1441.7 31049.5 31320.4 31399.2 31471.8 32197.7 49302.3 49779.0 50202.7 50220.2 50221.7 306472.0 311892.0 312313.0 312838.0 313877.0 30545.5 31148.0 33914.7 34189.3 35029.3 39664.8 39980.4 40164.8 40579.2 40726.6 34206.3 37277.8 37420.6 37786.0 45925.8 39148.8 39176.4 39674.6 40097.6 40210.0 38777.6 39120.0 39280.6 39372.0 39408.8 34169.3 35144.3 35159.0 36108.1 36366.6 26818.7 27027.2 27084.6 27435.0 28154.4 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,085 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:34427.68 Median:3155.99 stddev:111080.52 (3519.7%) stddev-sans-first:111362.68 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: Values: 366.4 367.9 369.3 370.3 374.1 15589.8 17289.4 17576.4 18222.1 18783.2 18574.1 18673.4 18871.8 19283.8 19293.9 5576.2 5695.1 5707.4 5713.4 5715.2 4191.9 4309.4 5574.2 6023.8 6210.6 6126.8 6140.8 6157.7 6168.1 6203.4 6748.5 6954.3 6967.9 6978.0 6999.9 6595.3 6674.9 6824.2 6834.2 6839.5 24338.0 24384.5 24606.6 24710.1 25870.2 2428.2 2523.2 2591.2 2636.1 2657.3 15042.6 15656.3 15699.0 15840.9 16071.6 2092.5 2111.9 2144.2 2189.4 2196.0 68689.0 71091.0 73013.6 73238.0 73463.6 16304.2 16835.2 16855.4 16866.2 16882.3 26790.7 27042.7 27160.0 27309.9 27472.0 9411.6 9526.9 9542.0 9584.7 9608.5 8043.7 8068.8 8069.0 8111.2 8116.3 8258.4 8293.0 8312.6 8315.7 8365.7 2339.7 2664.5 3144.9 3167.0 3207.4 981.6 1002.6 1027.0 1042.2 1138.4 11644.7 18166.5 18169.4 18289.6 18924.0 482.4 483.6 491.7 500.3 503.4 6384.2 6568.4 6603.4 6616.5 6624.2 620976.0 632241.0 705587.0 720258.0 721453.0 47705.8 48623.3 89004.0 90519.0 91570.0 503.6 512.8 514.6 515.6 516.1 10812.8 10876.7 11200.2 11201.4 11372.1 36414.3 36833.6 38587.8 40080.0 40091.4 47171.5 47259.0 47865.3 48784.0 50963.2 9989.8 10127.8 10417.0 10432.3 10432.5 19036.7 19201.5 19279.0 19292.3 19481.1 14939.6 15545.8 16050.9 16428.6 16504.3 15758.9 16011.9 16400.7 16505.0 16862.9 15634.3 16070.4 16308.0 16542.2 16946.2 10051.4 10197.3 10616.6 10642.3 10725.9 16317.6 16381.5 16412.9 16433.3 16454.7 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5773.11 Median:6826.53 stddev:2976.87 (43.6%) stddev-sans-first:2968.49 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: Values: 1573.2 1595.6 1681.2 1732.5 1734.0 7964.5 8356.7 8366.5 8371.7 8375.0 7245.8 7439.5 7820.4 8028.3 8246.8 2367.9 2383.5 2407.3 2407.5 2411.0 2125.2 2411.6 2423.5 2461.1 2463.1 2132.7 2354.8 2403.4 2410.2 2410.3 2370.1 2400.0 2409.2 2410.8 2411.0 2291.3 2335.0 2338.8 2377.2 2378.4 8163.0 8228.3 8231.0 8258.7 8262.0 4808.4 4884.6 4890.2 4900.5 4901.7 3138.6 3145.8 3146.3 3199.4 3236.8 982.4 989.8 998.8 1004.6 1027.8 11312.8 11341.1 11373.1 11402.6 11418.5 4558.9 4564.0 4613.5 4629.2 4639.0 9773.8 9774.8 9799.2 9804.3 9826.1 5103.3 5128.2 5135.2 5135.9 5143.4 4602.9 4611.6 4618.3 4664.2 4669.3 3940.6 3967.1 3975.9 3982.7 4027.8 1143.1 1145.4 1190.1 1200.9 1247.4 1131.3 1135.7 1143.3 1327.5 1383.4 7842.6 7844.6 7904.2 7926.0 7969.6 1188.5 1190.4 1195.7 1200.2 1203.4 6195.2 6239.5 6265.2 6274.9 6320.2 9178.4 9425.3 9438.9 9469.6 9486.1 9349.1 9369.0 9371.0 9390.4 9399.8 7635.1 7839.7 7842.5 7842.8 7851.1 7853.4 7861.6 7938.5 7959.4 8002.7 8898.3 9107.4 9115.0 9149.0 9173.4 9117.5 9165.0 9181.7 9358.1 9485.2 7079.7 7152.5 7163.3 7166.2 7176.9 7542.5 7605.0 7627.5 7629.4 7640.1 6903.4 7090.1 7145.4 7204.4 7312.3 6899.1 7437.9 7454.3 7492.7 7580.8 7695.8 7720.8 7732.0 7742.6 7744.0 6730.3 6750.7 6759.1 6761.1 6892.0 7064.7 7078.9 7116.7 7118.3 7123.1 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5617.36 Median:6353.11 stddev:1681.04 (26.5%) stddev-sans-first:1638.85 16:31:31 INFO - 2016-02-09 16:31:31,087 DEBUG : BROWSER_OUTPUT: Values: 363.7 365.8 366.6 368.8 373.1 6205.4 6232.8 6288.6 6297.2 6334.1 5974.2 6011.6 6120.8 6231.0 6259.6 4836.2 4841.8 4861.9 4862.5 4864.8 4674.9 4862.5 4863.1 4868.0 4869.9 4669.2 4753.9 4767.3 4818.9 4845.8 4792.1 4810.1 4814.9 4815.3 4831.7 4255.7 4256.8 4257.9 4276.0 4276.4 5797.3 5914.8 5927.1 5929.2 5933.6 5660.1 5666.9 5672.0 5674.8 5681.6 6622.4 6632.7 6636.4 6636.7 6641.7 4751.7 4756.8 4756.9 4758.3 4764.8 6772.1 6939.1 6947.1 6951.7 6951.9 4881.5 4883.0 4884.2 4887.2 4889.1 4938.5 4949.1 4951.3 4955.5 4960.3 5402.9 5608.4 5629.4 5639.5 5639.9 6722.6 6725.8 6726.7 6727.2 6729.6 1688.1 1716.7 1719.9 1720.6 1721.3 1145.0 1146.1 1151.9 1153.0 1178.2 6477.1 6512.9 6516.0 6517.4 6523.7 6648.4 6940.5 6943.0 6944.8 6946.8 6372.1 6391.0 6510.1 6551.7 6554.6 3700.4 3754.4 3756.5 3756.5 3758.6 6976.6 7166.8 7174.2 7184.7 7192.5 7161.7 7168.2 7175.8 7177.2 7180.3 7175.3 7178.1 7181.8 7184.9 7187.8 6850.3 6948.2 6959.6 6959.8 6977.1 6858.2 6863.5 6867.4 6873.9 6874.7 7143.6 7145.2 7149.4 7149.4 7157.1 4656.3 4717.2 4748.0 4757.2 4774.6 6914.9 6920.8 6924.5 6930.1 6940.7 6749.6 6751.3 6757.7 6765.2 6769.2 6574.0 6725.6 6740.8 6746.3 6758.9 6632.6 6632.8 6647.4 6650.5 6651.2 6744.0 6768.8 6769.2 6778.0 6779.2 6286.0 6379.3 6523.5 6523.6 6525.3 16:31:31 INFO - 2016-02-09 16:31:31,088 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,088 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14788.44 Median:4585.21 stddev:17376.59 (379.0%) stddev-sans-first:17393.75 16:31:31 INFO - 2016-02-09 16:31:31,089 DEBUG : BROWSER_OUTPUT: Values: 838.4 884.0 909.1 914.6 953.0 13777.2 14165.9 14167.3 14204.6 14222.4 13007.2 13071.2 13073.9 13079.8 13089.5 761.1 774.8 912.6 927.5 931.1 918.7 921.9 930.5 933.2 933.5 807.2 955.5 967.5 967.6 968.0 808.9 945.1 962.2 966.1 966.4 714.3 717.4 718.2 718.8 719.4 13305.6 13359.6 13407.5 13410.7 13411.6 12407.7 12456.1 12651.7 12977.1 13381.8 10516.3 10633.5 10719.6 10961.6 11269.7 3775.6 3796.0 3806.9 3821.1 3829.4 43872.1 44566.0 47156.3 50704.2 50719.5 9935.7 10098.9 10461.3 10499.3 10555.2 19076.9 19307.0 19321.0 19370.3 19372.0 7578.2 7594.8 7659.3 7760.0 7869.1 4264.7 4415.9 4423.2 4553.2 4617.2 3200.1 3213.3 3214.0 3220.5 3221.3 587.9 595.7 602.1 609.7 616.5 617.9 682.3 687.5 748.1 748.6 8573.5 9159.0 9261.4 9406.5 9461.0 703.7 715.3 722.5 729.7 757.2 4270.5 4355.5 4384.1 4408.7 4454.6 58370.7 62739.0 63115.7 69270.3 70122.3 54547.7 54622.0 54658.5 55609.4 57132.0 48403.0 48983.3 50633.5 51308.2 51563.2 8744.6 8812.7 8863.7 9099.3 9103.1 38280.2 38305.4 38662.8 39771.4 41775.2 48945.0 49561.0 49685.5 50814.4 51079.5 11563.1 11752.4 11938.2 12153.5 12455.8 12794.7 12898.5 12920.4 13689.3 14437.9 11840.8 12088.7 12250.6 12365.9 12594.0 11656.5 11772.7 12278.1 12666.1 12816.9 11549.4 11702.6 11749.3 11956.2 11981.6 10918.7 11167.2 11171.4 11245.7 11487.4 9620.3 9933.7 9949.4 9992.5 10191.6 16:31:31 INFO - 2016-02-09 16:31:31,089 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:31:31 INFO - 2016-02-09 16:31:31,089 DEBUG : BROWSER_OUTPUT: 16:31:31 INFO - 2016-02-09 16:31:31,614 INFO : Browser exited with error code: 0 16:31:31 INFO - 2016-02-09 16:31:31,637 INFO : Completed test dromaeo_css (00:18:31) 16:31:31 INFO - 2016-02-09 16:31:31,637 INFO : Starting test kraken 16:31:31 INFO - 2016-02-09 16:31:31,637 DEBUG : operating with platform_type : linux_ 16:31:31 INFO - 2016-02-09 16:31:31,638 INFO : Initialising browser for kraken test... 16:31:31 INFO - 2016-02-09 16:31:31,644 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9RJ9OF/profile http://localhost:42058/getInfo.html 16:31:35 INFO - 2016-02-09 16:31:35,186 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:31:35 INFO - 2016-02-09 16:31:35,186 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:31:35 INFO - 2016-02-09 16:31:35,186 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:31:35 INFO - 2016-02-09 16:31:35,186 DEBUG : BROWSER_OUTPUT: __metrics 16:31:35 INFO - 2016-02-09 16:31:35,476 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:31:36 INFO - 2016-02-09 16:31:36,318 INFO : Browser initialized. 16:31:36 INFO - 2016-02-09 16:31:36,319 INFO : Running cycle 1/1 for kraken test... 16:31:36 INFO - 2016-02-09 16:31:36,319 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9RJ9OF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:31:48 INFO - 2016-02-09 16:31:48,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 133730304 16:31:48 INFO - 2016-02-09 16:31:48,042 DEBUG : BROWSER_OUTPUT: 16:31:50 INFO - 2016-02-09 16:31:50,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42058/tests/kraken/driver.html?testName=audio-beat-detection) 16:31:51 INFO - 2016-02-09 16:31:51,068 DEBUG : BROWSER_OUTPUT: RSS: Main: 165527552 16:31:51 INFO - 2016-02-09 16:31:51,068 DEBUG : BROWSER_OUTPUT: 16:31:53 INFO - 2016-02-09 16:31:53,658 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42058/tests/kraken/driver.html?testName=audio-dft) 16:31:54 INFO - 2016-02-09 16:31:54,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 161144832 16:31:54 INFO - 2016-02-09 16:31:54,224 DEBUG : BROWSER_OUTPUT: 16:31:57 INFO - 2016-02-09 16:31:57,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42058/tests/kraken/driver.html?testName=audio-fft) 16:31:57 INFO - 2016-02-09 16:31:57,574 DEBUG : BROWSER_OUTPUT: RSS: Main: 158965760 16:31:57 INFO - 2016-02-09 16:31:57,574 DEBUG : BROWSER_OUTPUT: 16:31:59 INFO - 2016-02-09 16:31:59,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42058/tests/kraken/driver.html?testName=audio-oscillator) 16:32:00 INFO - 2016-02-09 16:32:00,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 158117888 16:32:00 INFO - 2016-02-09 16:32:00,121 DEBUG : BROWSER_OUTPUT: 16:32:02 INFO - 2016-02-09 16:32:02,297 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42058/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:02 INFO - 2016-02-09 16:32:02,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 159936512 16:32:02 INFO - 2016-02-09 16:32:02,860 DEBUG : BROWSER_OUTPUT: 16:32:05 INFO - 2016-02-09 16:32:05,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42058/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:06 INFO - 2016-02-09 16:32:06,239 DEBUG : BROWSER_OUTPUT: RSS: Main: 157896704 16:32:06 INFO - 2016-02-09 16:32:06,239 DEBUG : BROWSER_OUTPUT: 16:32:09 INFO - 2016-02-09 16:32:09,551 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42058/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:10 INFO - 2016-02-09 16:32:10,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 158089216 16:32:10 INFO - 2016-02-09 16:32:10,118 DEBUG : BROWSER_OUTPUT: 16:32:12 INFO - 2016-02-09 16:32:12,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42058/tests/kraken/driver.html?testName=json-parse-financial) 16:32:13 INFO - 2016-02-09 16:32:13,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 159420416 16:32:13 INFO - 2016-02-09 16:32:13,378 DEBUG : BROWSER_OUTPUT: 16:32:14 INFO - 2016-02-09 16:32:14,915 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42058/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:15 INFO - 2016-02-09 16:32:15,477 DEBUG : BROWSER_OUTPUT: RSS: Main: 164978688 16:32:15 INFO - 2016-02-09 16:32:15,477 DEBUG : BROWSER_OUTPUT: 16:32:17 INFO - 2016-02-09 16:32:17,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:17 INFO - 2016-02-09 16:32:17,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 163954688 16:32:17 INFO - 2016-02-09 16:32:17,818 DEBUG : BROWSER_OUTPUT: 16:32:19 INFO - 2016-02-09 16:32:19,770 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:20 INFO - 2016-02-09 16:32:20,340 DEBUG : BROWSER_OUTPUT: RSS: Main: 169185280 16:32:20 INFO - 2016-02-09 16:32:20,340 DEBUG : BROWSER_OUTPUT: 16:32:22 INFO - 2016-02-09 16:32:22,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:23 INFO - 2016-02-09 16:32:23,301 DEBUG : BROWSER_OUTPUT: RSS: Main: 174055424 16:32:23 INFO - 2016-02-09 16:32:23,301 DEBUG : BROWSER_OUTPUT: 16:32:25 INFO - 2016-02-09 16:32:25,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:32:26 INFO - 2016-02-09 16:32:26,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 173342720 16:32:26 INFO - 2016-02-09 16:32:26,368 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,018 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42058/tests/kraken/driver.html?testName=ai-astar) 16:32:28 INFO - 2016-02-09 16:32:28,264 DEBUG : BROWSER_OUTPUT: RSS: Main: 231460864 16:32:28 INFO - 2016-02-09 16:32:28,264 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,267 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:32:28 INFO - 2016-02-09 16:32:28,267 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:32:28 INFO - 2016-02-09 16:32:28,267 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;102;108;110;109;99;108;108;99 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;122;158;157;122;154;153;123;173;121 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;161;158;161;159;159;160;159;158 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;72;145;75;73;73;73;156;74 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;143;92;93;92;136;92;93;136 16:32:28 INFO - 2016-02-09 16:32:28,268 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;109;109;109;109;109 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;146;146;145;146;145;146;145 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;95;95;95;96;96;95 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;58;58;57;58;58;59;58;54;54 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;63;64;64;64;63;64;64 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;77;79;79;78;80;78;78;78;78 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;114;114;116;123;115;114;115;116;110 16:32:28 INFO - 2016-02-09 16:32:28,269 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;149;137;144;144;143;132;142;141;143;138 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;67;68;67;66;66;66;64;64;67 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,883 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: __startTimestamp1455064348265__endTimestamp 16:32:28 INFO - 2016-02-09 16:32:28,270 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.00 Median:109.00 stddev:4.27 (3.9%) stddev-sans-first:4.47 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 102.0 108.0 110.0 109.0 99.0 108.0 108.0 99.0 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.00 Median:153.50 stddev:19.22 (12.5%) stddev-sans-first:20.33 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: Values: 147.0 122.0 158.0 157.0 122.0 154.0 153.0 123.0 173.0 121.0 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,274 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.40 Median:159.50 stddev:1.07 (0.7%) stddev-sans-first:1.13 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 161.0 158.0 161.0 159.0 159.0 160.0 159.0 158.0 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.50 Median:73.00 stddev:32.79 (44.9%) stddev-sans-first:34.23 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 72.0 145.0 75.0 73.0 73.0 73.0 156.0 74.0 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:22.26 (24.2%) stddev-sans-first:23.01 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 143.0 92.0 93.0 92.0 136.0 92.0 93.0 136.0 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 16:32:28 INFO - 2016-02-09 16:32:28,275 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 146.0 146.0 145.0 146.0 145.0 146.0 145.0 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.00 Median:58.00 stddev:1.76 (3.0%) stddev-sans-first:1.83 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: Values: 56.0 58.0 58.0 57.0 58.0 58.0 59.0 58.0 54.0 54.0 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.50 Median:64.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 63.0 64.0 64.0 64.0 63.0 64.0 64.0 16:32:28 INFO - 2016-02-09 16:32:28,276 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:1.10 (1.4%) stddev-sans-first:0.87 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: Values: 76.0 77.0 79.0 79.0 78.0 80.0 78.0 78.0 78.0 78.0 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:115.50 stddev:3.44 (3.0%) stddev-sans-first:3.42 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: Values: 119.0 114.0 114.0 116.0 123.0 115.0 114.0 115.0 116.0 110.0 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.30 Median:143.00 stddev:4.67 (3.3%) stddev-sans-first:4.03 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: Values: 149.0 137.0 144.0 144.0 143.0 132.0 142.0 141.0 143.0 138.0 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.29 (1.9%) stddev-sans-first:1.36 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: Values: 66.0 67.0 68.0 67.0 66.0 66.0 66.0 64.0 64.0 67.0 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:32:28 INFO - 2016-02-09 16:32:28,277 DEBUG : BROWSER_OUTPUT: 16:32:28 INFO - 2016-02-09 16:32:28,918 INFO : Browser exited with error code: 0 16:32:28 INFO - 2016-02-09 16:32:28,969 INFO : Completed test kraken (00:00:57) 16:32:28 INFO - 2016-02-09 16:32:28,969 INFO : Starting test v8_7 16:32:28 INFO - 2016-02-09 16:32:28,969 DEBUG : operating with platform_type : linux_ 16:32:28 INFO - 2016-02-09 16:32:28,969 INFO : Initialising browser for v8_7 test... 16:32:28 INFO - 2016-02-09 16:32:28,975 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKmrrgb/profile http://localhost:42058/getInfo.html 16:32:32 INFO - 2016-02-09 16:32:32,797 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:32:32 INFO - 2016-02-09 16:32:32,797 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:32:32 INFO - 2016-02-09 16:32:32,797 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:32:32 INFO - 2016-02-09 16:32:32,797 DEBUG : BROWSER_OUTPUT: __metrics 16:32:32 INFO - 2016-02-09 16:32:32,946 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:33 INFO - 2016-02-09 16:32:33,746 INFO : Browser initialized. 16:32:33 INFO - 2016-02-09 16:32:33,746 INFO : Running cycle 1/1 for v8_7 test... 16:32:33 INFO - 2016-02-09 16:32:33,747 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKmrrgb/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:32:45 INFO - 2016-02-09 16:32:45,407 DEBUG : BROWSER_OUTPUT: RSS: Main: 134103040 16:32:45 INFO - 2016-02-09 16:32:45,407 DEBUG : BROWSER_OUTPUT: 16:33:06 INFO - 2016-02-09 16:33:06,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42058/tests/v8_7/run.html (next: http://localhost:42058/tests/v8_7/run.html) 16:33:07 INFO - 2016-02-09 16:33:07,067 DEBUG : BROWSER_OUTPUT: RSS: Main: 425709568 16:33:07 INFO - 2016-02-09 16:33:07,067 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,067 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |0;Richards;172.20595832615808 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;166.6944490748458 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.214848143982 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5181.347150259067 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |4;RayTrace;962.4639076034649 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |5;Earley;543.1830526887561 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |6;Boyer;10500 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |7;RegExp;24585.365853658535 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |8;Splay;509.6056622851365 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: __startTimestamp1455064387060__endTimestamp 16:33:07 INFO - 2016-02-09 16:33:07,068 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:172.21 Median:172.21 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: Values: 172.2 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:166.69 Median:166.69 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: Values: 166.7 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.21 Median:281.21 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: Values: 281.2 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5181.35 Median:5181.35 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: Values: 5181.3 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,069 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:962.46 Median:962.46 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: Values: 962.5 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.18 Median:543.18 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: Values: 543.2 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10500.00 Median:10500.00 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: Values: 10500.0 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24585.37 Median:24585.37 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,070 DEBUG : BROWSER_OUTPUT: Values: 24585.4 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:509.61 Median:509.61 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: Values: 509.6 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: Values: 4830.9 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:33:07 INFO - 2016-02-09 16:33:07,071 DEBUG : BROWSER_OUTPUT: 16:33:07 INFO - 2016-02-09 16:33:07,750 INFO : Browser exited with error code: 0 16:33:07 INFO - 2016-02-09 16:33:07,767 INFO : Completed test v8_7 (00:00:38) 16:33:08 INFO - 2016-02-09 16:33:08,104 INFO : Completed test suite (00:20:08) 16:33:08 INFO - 2016-02-09 16:33:08,104 DEBUG : Working with test: dromaeo_css 16:33:08 INFO - 2016-02-09 16:33:08,104 DEBUG : Generating results file: dromaeo_css 16:33:08 INFO - 2016-02-09 16:33:08,106 DEBUG : Working with test: kraken 16:33:08 INFO - 2016-02-09 16:33:08,106 DEBUG : Generating results file: kraken 16:33:08 INFO - 2016-02-09 16:33:08,106 INFO : javascript benchmark 16:33:08 INFO - 2016-02-09 16:33:08,107 DEBUG : Working with test: v8_7 16:33:08 INFO - 2016-02-09 16:33:08,107 DEBUG : Generating results file: v8_7 16:33:08 INFO - 2016-02-09 16:33:08,107 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:33:08 INFO - 2016-02-09 16:33:08,158 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:33:08 INFO - 2016-02-09 16:33:08,159 DEBUG : process_Request line: dromaeo_css 7526.57 graph.html#tests=[[72,53,35]] 16:33:08 INFO - 2016-02-09 16:33:08,159 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:33:08 INFO - 2016-02-09 16:33:08,190 DEBUG : process_Request line: kraken 1475.10 graph.html#tests=[[232,53,35]] 16:33:08 INFO - 2016-02-09 16:33:08,190 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:33:08 INFO - 2016-02-09 16:33:08,221 DEBUG : process_Request line: v8_7 23179.97 graph.html#tests=[[230,53,35]] 16:33:08 INFO - 2016-02-09 16:33:08,222 INFO : javascript benchmark 16:33:08 INFO - 2016-02-09 16:33:08,226 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3276.901692949212, 3356.7985920844767, 3381.7632765706394, 3385.6952874241315, 3410.982945085275, 5555.166724991253, 5561.304836895388, 5583.07879199932, 5583.664980101192, 5592.07730724431, 5542.279503664528, 5570.916436253449, 5588.329400471949, 5591.2731344925105, 5595.020871347519, 4928.67963582366, 5004.149294619905, 5063.594912406998, 5080.618953578478, 5143.53708166265, 5039.21892106724, 5051.520105589963, 5094.312267843849, 5103.209002604603, 5108.719020453892, 4993.1262029144755, 5041.773120209581, 5081.1108056090225, 5104.259882317062, 5128.333316668846, 5060.645754797166, 5072.797088116471, 5096.821611243608, 5098.617603679739, 5117.385913690344, 4829.975850120727, 4982.153033984231, 4995.200767877159, 5053.4946505349535, 5059.114654935372, 5218.634695571312, 5254.133068043769, 5307.495787900143, 5314.070037743418, 5336.626436149471, 5955.464008239236, 5962.165296858443, 6005.069214271794, 6013.338532761392, 6023.488003519706, 5696.857578560528, 5753.108268218432, 6024.126501657235, 6034.456898879078, 6037.335893051761, 5421.430749771257, 5478.123500239983, 5481.260029895953, 5511.751971161308, 5512.283403157604, 6105.84735381619, 6111.0, 6111.144439778434, 6120.479759220427, 6167.753289868355, 5668.319801623831, 5787.710614469344, 5852.15143804146, 5922.3485416604135, 5926.8814623707285, 6007.879842403127, 6027.547933904974, 6033.607815491979, 6044.395560443921, 6045.8488537786025, 5944.108383742473, 5949.0481522956115, 5987.341392446828, 5997.340292567814, 6005.879882402328, 5724.084146536534, 5792.478676919013, 5824.388439213906, 5906.468417842329, 5967.254093238345, 4554.817807287672, 4597.287420449768, 4601.677882548256, 4608.308753686973, 4644.349791029259, 4079.796010199478, 4116.506019277646, 4142.399352093929, 4146.0464093258715, 4162.771047592411, 5537.501624853702, 5560.332760068818, 5618.831435056954, 5689.544836413077, 5714.314282286152, 6004.249468816398, 6009.399060093956, 6018.247719035121, 6023.548233882389, 6064.575479716377, 5460.6177567570685, 5699.458551437607, 5727.885442291131, 5748.540116790647, 5755.309362876482, 5110.565601923804, 5125.8718532036255, 5164.122099243138, 5184.7407629618365, 5188.066148093304, 6129.877402452015, 6190.566660333733, 6195.659238741912, 6197.287311959182, 6201.10084037812, 5842.853928651818, 5971.223740913653, 6032.366601506778, 6041.063635136561, 6050.485708714633, 5980.222571065734, 6047.213862197887, 6053.031514957585, 6056.0, 6084.90872636902, 5903.203067585821, 5929.85175370602, 5947.553933455095, 5988.311344195299, 5990.7903223386975, 5768.971155144365, 5878.823635290948, 5922.644641321534, 6020.217371741821, 6049.274087109575, 5822.0684690449325, 5823.301203855564, 5823.330317013598, 5825.825225243249, 5858.0, 5788.9131663024245, 5803.680797556175, 5807.361190268983, 5808.94191058084, 5816.3892791258, 5519.944800551943, 5572.972135139461, 5708.914366284426, 5736.0248757712125, 5838.3869693683255, 5806.4193580641595, 5822.70886455667, 5839.883202336015, 5846.35690074083, 5851.707414629166, 5684.9431505684415, 5717.914231286451, 5721.256236689203, 5724.627899186539, 5725.599208055396], "unit": "ms", "name": "dojo.html", "value": 5489.086607158752}, {"lowerIsBetter": false, "replicates": [594.8245267646043, 624.5971348480227, 637.9011253255744, 639.5682914033026, 640.3500447046249, 33204.66795332046, 33862.49206261905, 42813.571864281344, 43375.13249735011, 43545.12909741803, 46984.295235571495, 47275.76362118193, 47307.526924730744, 47559.0488190236, 47630.52369476304, 8069.0, 8166.918330816675, 9094.090590940887, 9252.583633736464, 9266.721998340026, 10923.781524369508, 11072.723181920475, 11128.777424451506, 11158.721031974184, 11165.83251251232, 10788.136949044097, 10845.728856778565, 10851.240413171081, 10855.294405863633, 10862.945685271563, 10796.784064318708, 10887.891121088845, 10892.836607450894, 10895.073918716918, 10902.127829773639, 10641.893581064167, 10767.83848242277, 10785.245032847704, 10785.514651840607, 10790.298630588986, 43620.78189609064, 43686.0, 43835.0, 43933.78033109846, 44102.33846492305, 3359.6136444308972, 3436.8453419596176, 3477.7217822574385, 3710.9794806428176, 3753.887383378503, 33245.50131748026, 33264.83367583171, 33271.16822079443, 33496.83251583751, 33904.83047584746, 4140.358244472111, 4169.259799552883, 4211.263028969918, 4278.251306021465, 4347.7173983690955, 459284.0, 462620.0, 463680.0, 469890.0, 471107.0, 60843.69578152081, 61903.38096618976, 66550.33449665441, 67304.32695673079, 67313.66343168351, 73222.26777732262, 74294.0, 74536.25463745401, 74546.0, 74561.0, 14834.10995340283, 15056.924715376572, 15067.246637668073, 15086.321115549827, 15086.471973481095, 12417.130800844036, 12540.310282934344, 12557.183783054072, 12558.74882502345, 12567.685807854878, 21916.45208869759, 22786.20248291335, 22865.0, 22957.426064348525, 22975.88512057429, 8416.747497575083, 8601.35489838252, 8657.177568131017, 8692.348073894484, 8742.213200811957, 1031.2832581355885, 1097.5170924793065, 1130.8982191602795, 1158.3571118029406, 1175.8588969323737, 29898.40203195924, 30016.54975175374, 30668.386632267677, 30846.0, 31018.0, 1058.0636137018794, 1066.2376400873413, 1102.795982743195, 1112.7996960547018, 1230.772307123185, 14568.708625827636, 15216.695666086616, 15429.459968901034, 17526.386576470017, 17806.91096544509, 441130.0, 444537.0, 449143.0, 449242.0, 449346.0, 59216.40783592195, 59712.0, 59759.0, 59809.0, 59820.0, 1423.9145651260958, 1426.023174125748, 1433.0, 1437.5615437291726, 1441.7332793433247, 31049.534256985713, 31320.37359252848, 31399.215019623778, 31471.84264078665, 32197.67802321947, 49302.26046609232, 49779.0, 50202.74898625483, 50220.24669630032, 50221.74889125531, 306472.0, 311892.0, 312313.0, 312838.0, 313877.0, 30545.54181687232, 31148.0, 33914.66085339115, 34189.31621367609, 35029.29941401107, 39664.80167599259, 39980.40029399503, 40164.79917600393, 40579.1884162321, 40726.59273407228, 34206.315873682885, 37277.81361093177, 37420.62579374172, 37786.0, 45925.770371149265, 39148.847542349395, 39176.41235381414, 39674.60325396709, 40097.59902401055, 40210.0, 38777.61222387853, 39120.0, 39280.607193927695, 39372.0, 39408.80295598504, 34169.31661366709, 35144.297114057066, 35159.0, 36108.09729756672, 36366.63633363633, 26818.731812681624, 27027.189184324503, 27084.593731094443, 27435.0, 28154.37828153334], "unit": "ms", "name": "ext.html", "value": 20684.06926443121}, {"lowerIsBetter": false, "replicates": [366.369843868546, 367.8933109398275, 369.3001761661651, 370.27241470510444, 374.10028880542296, 15589.766153507706, 17289.394871179513, 17576.384826531073, 18222.088895555236, 18783.154758035886, 18574.07129643516, 18673.439796806084, 18871.811281887178, 19283.807161928376, 19293.903530482363, 5576.219329293912, 5695.145728140791, 5707.372189059221, 5713.371529131813, 5715.199872017921, 4191.937120943188, 4309.37514060461, 5574.247476590669, 6023.819285421422, 6210.565260431772, 6126.7855625053135, 6140.754369825202, 6157.692115394212, 6168.105624684418, 6203.441690247877, 6748.493862960273, 6954.3045695430055, 6967.895481567755, 6977.965110174468, 6999.860002799941, 6595.340465953414, 6674.93325066748, 6824.18109826819, 6834.179898412172, 6839.487038472134, 24338.0, 24384.512309753707, 24606.630900536507, 24710.13514526983, 25870.223893283233, 2428.1501474483966, 2523.1547431610247, 2591.183777110223, 2636.0905487606747, 2657.3356660834793, 15042.623934401727, 15656.295466704025, 15699.0, 15840.920795396065, 16071.598210044725, 2092.5082605587677, 2111.862728922615, 2144.1959265275523, 2189.386971647941, 2195.9560808783735, 68689.0, 71091.0, 73013.634931825, 73238.0, 73463.63268183624, 16304.184790760652, 16835.242414091394, 16855.410060647817, 16866.156692165343, 16882.324707011827, 26790.732092678823, 27042.729572704415, 27160.0, 27309.863450683017, 27472.0, 9411.576479058322, 9526.90473095274, 9542.045795420403, 9584.66453674132, 9608.471534065553, 8043.678252869937, 8068.757937261774, 8068.9596552016865, 8111.229433203836, 8116.310113640288, 8258.380621453294, 8292.958535207285, 8312.625931833081, 8315.667373305121, 8365.707200247962, 2339.695839540883, 2664.537697719667, 3144.9371012579622, 3167.0340546133652, 3207.3905957867933, 981.5950920245427, 1002.5939494504737, 1027.0138477690853, 1042.2183362478142, 1138.4307846076797, 11644.708882278012, 18166.5458363542, 18169.36407225741, 18289.634207316045, 18924.0, 482.4018217410456, 483.64210484242113, 491.6582974832457, 500.2596157686644, 503.43866588753275, 6384.170057892537, 6568.441682457044, 6603.438707709803, 6616.503762217853, 6624.171978502687, 620976.0, 632241.0, 705587.0, 720258.0, 721453.0, 47705.76147119312, 48623.27065094026, 89004.0, 90519.0, 91570.0, 503.6097024805805, 512.7641285008863, 514.5574805667198, 515.6261710260031, 516.0994065355978, 10812.837807433052, 10876.673699789018, 11200.21598488098, 11201.43992800373, 11372.090232781524, 36414.27171456609, 36833.63166368409, 38587.807060964515, 40080.0, 40091.39862902117, 47171.52828471671, 47259.0, 47865.28202076902, 48784.024319512704, 50963.2355514675, 9989.800203996025, 10127.79744405093, 10417.0, 10432.269741118049, 10432.478376081013, 19036.714449282994, 19201.51996200106, 19279.0, 19292.32476863303, 19481.123349449594, 14939.626509337353, 15545.766813498032, 16050.919745401197, 16428.589285267484, 16504.25730842091, 15758.921205393899, 16011.919940400223, 16400.67198656044, 16505.0, 16862.915685421496, 15634.29645665925, 16070.35718571244, 16308.0, 16542.172891355145, 16946.15269236558, 10051.447170405405, 10197.337173084023, 10616.628418005334, 10642.308249963728, 10725.946370268412, 16317.59206019859, 16381.508554743377, 16412.917935410245, 16433.34266629369, 16454.67090658204], "unit": "ms", "name": "jquery.html", "value": 9858.228766288361}, {"lowerIsBetter": false, "replicates": [1573.2133933033483, 1595.609075776434, 1681.1930273468736, 1732.4542769027764, 1734.020278542623, 7964.482308649941, 8356.665733370673, 8366.456180348274, 8371.706990255343, 8375.036870759865, 7245.818854528627, 7439.4792364534505, 7820.3743700503965, 8028.317593004587, 8246.835063298731, 2367.9052837886506, 2383.4875501767115, 2407.3018824540864, 2407.5425669122897, 2410.9758902411013, 2125.192426877792, 2411.6141417373224, 2423.466837295792, 2461.052494789501, 2463.08865719684, 2132.722746043012, 2354.7645235476402, 2403.387136280246, 2410.156445244168, 2410.276916924924, 2370.099362242337, 2399.9760002400017, 2409.1929203716777, 2410.807135429179, 2411.0476361837063, 2291.3126062181277, 2334.976650233493, 2338.75443078476, 2377.239283429303, 2378.4053986503372, 8163.0, 8228.25945664893, 8230.958845205736, 8258.710945116953, 8262.049864265626, 4808.447028591721, 4884.5848102911295, 4890.24201248807, 4900.460949295574, 4901.730404827733, 3138.59198304219, 3145.811251324928, 3146.307812281317, 3199.376121656278, 3236.8057916525086, 982.3761911186448, 989.8119357322086, 998.7902540466438, 1004.5881188712666, 1027.825269704152, 11312.830307545393, 11341.092712583057, 11373.090152787758, 11402.600908968147, 11418.54325826966, 4558.908821823545, 4564.041551274203, 4613.469451013109, 4629.213033784265, 4638.953610463852, 9773.804523909483, 9774.755631109138, 9799.216062714966, 9804.313698041211, 9826.115649591571, 5103.2855400244, 5128.230765385222, 5135.178371460548, 5135.87160320995, 5143.434222235551, 4602.861914142581, 4611.631069514431, 4618.261078227468, 4664.183767840615, 4669.252919532859, 3940.5862384449792, 3967.0677390813134, 3975.860844870416, 3982.7411218270718, 4027.81874815634, 1143.1369316166408, 1145.3643228008368, 1190.1014733875868, 1200.9099317551045, 1247.4324182497123, 1131.332513816852, 1135.7274254178938, 1143.308298479408, 1327.5486334646266, 1383.4466213514474, 7842.5686587237105, 7844.568548729761, 7904.20957904205, 7925.960370198112, 7969.641366138421, 1188.5127097889824, 1190.4107466803987, 1195.6950977500856, 1200.2498438475952, 1203.4043148641304, 6195.16365290689, 6239.469645080128, 6265.185525881606, 6274.874502509924, 6320.241571011416, 9178.357514973888, 9425.2931030173, 9438.90561094394, 9469.621215151317, 9486.098820612031, 9349.111834375723, 9369.016253293443, 9370.95314523423, 9390.436573805593, 9399.812003759887, 7635.083789945242, 7839.725609603636, 7842.45102842807, 7842.803929901798, 7851.136374998746, 7853.37173026168, 7861.567613781068, 7938.5236885787035, 7959.403044771781, 8002.7199048033035, 8898.332625053017, 9107.362484626012, 9114.954425227832, 9149.0, 9173.35786494939, 9117.452952822852, 9164.95417522908, 9181.724548263563, 9358.11097945694, 9485.193758530602, 7079.716811327489, 7152.463565232732, 7163.283671632795, 7166.211716711262, 7176.892346614701, 7542.47202695806, 7604.9733286005685, 7627.466077374531, 7629.351505122127, 7640.0831900170115, 6903.447724181953, 7090.07828982229, 7145.356917877416, 7204.387627051759, 7312.3053309937595, 6899.132743186132, 7437.851242975218, 7454.291842274975, 7492.737754178577, 7580.8483830324185, 7695.846083078419, 7720.7683769487, 7732.0, 7742.612869356397, 7743.99328087345, 6730.32696730323, 6750.72997080131, 6759.053732477555, 6761.087253220752, 6891.965540172267, 7064.717411303491, 7078.929210708033, 7116.715331386687, 7118.323759242864, 7123.109611298588], "unit": "ms", "name": "mootools.html", "value": 4736.794879435946}, {"lowerIsBetter": false, "replicates": [363.70721569136845, 365.786015181119, 366.57843479997985, 368.8119059279768, 373.07105307783627, 6205.441510264075, 6232.84417889553, 6288.591241569295, 6297.181366422359, 6334.144890439789, 5974.193483879675, 6011.609245399046, 6120.785772497963, 6231.0, 6259.561830671855, 4836.226203807391, 4841.8063277468855, 4861.854144375674, 4862.465128835825, 4864.7567621618955, 4674.883127921812, 4862.465128835825, 4863.124637565237, 4867.975660121694, 4869.8782530436665, 4669.229577119771, 4753.881152971168, 4767.261074533453, 4818.927716084261, 4845.781939812717, 4792.137415265251, 4810.134175848346, 4814.855554333376, 4815.253635686439, 4831.710097394132, 4255.702100852942, 4256.787160641987, 4257.87226383209, 4276.037891474424, 4276.401303817468, 5797.275340582427, 5914.792982245601, 5927.1405646181065, 5929.169916211734, 5933.643981361132, 5660.122680984454, 5666.943330566724, 5672.0, 5674.829755107354, 5681.573881958829, 6622.37087476689, 6632.668366581652, 6636.402723754885, 6636.73453061877, 6641.734330626769, 4751.71489710615, 4756.7859446325, 4756.9286460703115, 4758.333833263346, 4764.8332308369045, 6772.08576842125, 6939.063226464413, 6947.062146610244, 6951.721931122749, 6951.89572156418, 4881.51184881509, 4882.9755851221225, 4884.169691152512, 4887.218045112836, 4889.119958407449, 4938.481459446706, 4949.109160351171, 4951.257311403318, 4955.529224723646, 4960.255961605788, 5402.945970540323, 5608.355039170466, 5629.352624448159, 5639.548836093103, 5639.915401268984, 6722.630255336004, 6725.831854203684, 6726.679295642995, 6727.192736871509, 6729.596224226562, 1688.0546893739545, 1716.6995775739197, 1719.9226034828462, 1720.6386658801541, 1721.3458885623384, 1144.9771004579861, 1146.140394703972, 1151.8617765867982, 1152.9711757206137, 1178.2164860367932, 6477.093206951031, 6512.902306465407, 6516.0, 6517.4134327910715, 6523.6738163092605, 6648.401643852077, 6940.479464040217, 6943.027976083353, 6944.826379340557, 6946.756863509854, 6372.076048972873, 6390.968045159838, 6510.121133646991, 6551.672416379162, 6554.573952693059, 3700.40793473043, 3754.3805272129794, 3756.492873462102, 3756.492873462102, 3758.567764707094, 6976.5814051157095, 7166.784996450115, 7174.246704096099, 7184.676689548983, 7192.496525243183, 7161.749338773118, 7168.175659799085, 7175.784726458214, 7177.246389129171, 7180.31786980236, 7175.282471752783, 7178.138623365126, 7181.784546463615, 7184.928150718531, 7187.784366469119, 6850.349216824394, 6948.166220053626, 6959.582425054513, 6959.826004349729, 6977.092977913042, 6858.245592984668, 6863.450923925974, 6867.381935625817, 6873.931260687329, 6874.690638921361, 7143.642817858982, 7145.249748776304, 7149.356557909813, 7149.428045756431, 7157.141143062658, 4656.324832899279, 4717.150912835723, 4748.0, 4757.21505951528, 4774.570288674036, 6914.861702765816, 6920.792376228721, 6924.549904256207, 6930.13373328334, 6940.6529673515115, 6749.595024298558, 6751.257361690308, 6757.662116894234, 6765.221999470124, 6769.153855768029, 6573.967130164318, 6725.630090345078, 6740.831479213059, 6746.257911629815, 6758.898616520854, 6632.635205063768, 6632.801015969529, 6647.401733843977, 6650.534462587766, 6651.201855777338, 6743.966280168568, 6768.796936091925, 6769.153855768029, 6778.0, 6779.15260592426, 6286.025666021774, 6379.298277189413, 6523.510736694861, 6523.575967562093, 6525.3148419417175], "unit": "ms", "name": "prototype.html", "value": 5075.59203867625}, {"lowerIsBetter": false, "replicates": [838.3712215838121, 883.982320353593, 909.0818273543719, 914.6341463414637, 953.0374321934848, 13777.242251676154, 14165.92917035416, 14167.291635418214, 14204.644883877909, 14222.43110275589, 13007.154534955222, 13071.150375225603, 13073.934630326836, 13079.803802942963, 13089.541866034691, 761.056290200152, 774.8179177893219, 912.6303846942, 927.4620719982394, 931.1154403316876, 918.6830543462495, 921.9400738951947, 930.4556834251971, 933.2021121940711, 933.4819175357708, 807.229096213118, 955.4697143085584, 967.4582233948976, 967.6226271754022, 967.9854802177973, 808.9312408445289, 945.0832692288503, 962.1581116523007, 966.120830044663, 966.367029595609, 714.2750108639735, 717.4153065251803, 718.2494293463343, 718.8346680263569, 719.363363423374, 13305.600831975056, 13359.599212023655, 13407.46370145193, 13410.731785364336, 13411.597652070453, 12407.689807754788, 12456.065795065315, 12651.683707907285, 12977.091603587753, 13381.799273010913, 10516.263861529696, 10633.46832658364, 10719.571217151304, 10961.56153753849, 11269.718257043558, 3775.565809931838, 3796.0320118369914, 3806.9428958565645, 3821.102041020386, 3829.3681542545446, 43872.12255754867, 44566.0, 47156.292655610196, 50704.23943640848, 50719.492805072216, 9935.65225217114, 10098.899011009942, 10461.320014199053, 10499.317544359592, 10555.155587552978, 19076.904615476833, 19307.0, 19321.0, 19370.322038728576, 19372.0, 7578.204288549733, 7594.81012974669, 7659.348955338859, 7760.0, 7869.055713314439, 4264.680148988777, 4415.911681766347, 4423.203823311835, 4553.203189441834, 4617.215073437524, 3200.0719791260276, 3213.2609499815176, 3213.9839300803346, 3220.5169224616025, 3221.291315910496, 587.8677297608004, 595.746807606774, 602.1359349333633, 609.7347653770624, 616.5499185594451, 617.9196704428485, 682.2631557917438, 687.4500399680336, 748.1097493982144, 748.6107224243362, 8573.528455934984, 9159.0, 9261.398009129385, 9406.529673516297, 9460.95269523648, 703.697410113656, 715.3275920634587, 722.5447967780265, 729.7081167533029, 757.23141011873, 4270.487541494978, 4355.455568053993, 4384.079343337935, 4408.6913916026215, 4454.643628509712, 58370.708146459845, 62739.0, 63115.6844215776, 69270.30729692639, 70122.29877701157, 54547.72726136344, 54622.0, 54658.45341546613, 55609.44390556042, 57132.0, 48402.999999999294, 48983.265251021265, 50633.493665063615, 51308.23037654438, 51563.22655160176, 8744.606492707717, 8812.691555795516, 8863.689770857989, 9099.31755118382, 9103.135202155785, 38280.234395312495, 38305.425418618186, 38662.80668596639, 39771.40342894801, 41775.16449670929, 48945.0, 49561.0, 49685.50314496809, 50814.42670159703, 51079.48920510747, 11563.132765042823, 11752.412379381169, 11938.22401543897, 12153.513859445524, 12455.813162802746, 12794.744105118032, 12898.548550800677, 12920.353982301036, 13689.315534223448, 14437.927810360881, 11840.763184736084, 12088.697782555506, 12250.571230006906, 12365.938170309091, 12594.0, 11656.475458604553, 11772.70568235801, 12278.140530162802, 12666.113372064236, 12816.935915320364, 11549.42252887369, 11702.590409335633, 11749.29504229749, 11956.163068585463, 11981.580644677395, 10918.672439826818, 11167.218294719292, 11171.441427928408, 11245.71885702864, 11487.425628718698, 9620.326577139813, 9933.65232216869, 9949.403035817873, 9992.500374981368, 10191.643292485023], "unit": "ms", "name": "yui.html", "value": 6755.16905712298}], "name": "dromaeo_css", "value": 7526.568061627195}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 102.0, 108.0, 110.0, 109.0, 99.0, 108.0, 108.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 106.0}, {"lowerIsBetter": true, "replicates": [147.0, 122.0, 158.0, 157.0, 122.0, 154.0, 153.0, 123.0, 173.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 161.0, 158.0, 161.0, 159.0, 159.0, 160.0, 159.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 159.4}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 72.0, 145.0, 75.0, 73.0, 73.0, 73.0, 156.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 88.5}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 143.0, 92.0, 93.0, 92.0, 136.0, 92.0, 93.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [56.0, 58.0, 58.0, 57.0, 58.0, 58.0, 59.0, 58.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 64.0, 63.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.5}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 79.0, 79.0, 78.0, 80.0, 78.0, 78.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.1}, {"lowerIsBetter": true, "replicates": [119.0, 114.0, 114.0, 116.0, 123.0, 115.0, 114.0, 115.0, 116.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.6}, {"lowerIsBetter": true, "replicates": [149.0, 137.0, 144.0, 144.0, 143.0, 132.0, 142.0, 141.0, 143.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.3}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0, 64.0, 64.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken", "value": 1475.0999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.20595832615808], "unit": "score", "name": "Richards", "value": 204.998714}, {"lowerIsBetter": false, "replicates": [166.6944490748458], "unit": "score", "name": "DeltaBlue", "value": 396.64188200000007}, {"lowerIsBetter": false, "replicates": [281.214848143982], "unit": "score", "name": "Encrypt", "value": 946.5396360000002}, {"lowerIsBetter": false, "replicates": [5181.347150259067], "unit": "score", "name": "Decrypt", "value": 51.372933}, {"lowerIsBetter": false, "replicates": [962.4639076034649], "unit": "score", "name": "RayTrace", "value": 768.8485710000002}, {"lowerIsBetter": false, "replicates": [543.1830526887561], "unit": "score", "name": "Earley", "value": 1226.958383}, {"lowerIsBetter": false, "replicates": [10500.0], "unit": "score", "name": "Boyer", "value": 63.47266666666663}, {"lowerIsBetter": false, "replicates": [24585.365853658535], "unit": "score", "name": "RegExp", "value": 37.05395337301591}, {"lowerIsBetter": false, "replicates": [509.6056622851365], "unit": "score", "name": "Splay", "value": 159.90991865079366}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23179.968175346145}]} 16:33:08 INFO - RETURN: dromaeo_css: 7526.57 16:33:08 INFO - RETURN: kraken: 1475.10 16:33:08 INFO - RETURN: v8_7: 23179.97 16:33:08 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23179.97"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7526.57"}}} 16:33:08 INFO - Return code: 0 16:33:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:33:08 INFO - # TBPL SUCCESS # 16:33:08 INFO - Running post-action listener: _resource_record_post_action 16:33:08 INFO - Running post-run listener: _resource_record_post_run 16:33:09 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 8384512; Write bytes: 401444864; Read time: 1644; Write time: 283220 16:33:09 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 6541312; Read time: 0; Write time: 2360 16:33:09 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 6623232; Write bytes: 394903552; Read time: 1584; Write time: 280860 16:33:09 INFO - Running post-run listener: _upload_blobber_files 16:33:09 INFO - Blob upload gear active. 16:33:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:33:09 INFO - Copying logs to upload dir... 16:33:09 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.749347 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-09 16:33:09.490834) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-09 16:33:09.494882) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010013 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455054376/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-09 16:33:09.546748) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:33:09.547138) ========= 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=574dc5aafe09851ec43d388c000001b6-1455062516.916447-1710994569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-09 16:33:09.598005) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-09 16:33:09.598389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-09 16:33:09.598745) ========= ========= Total master_lag: 0.25 =========