builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1455231897.76 results: success (0) buildid: 20160211125933 builduid: 9e924c2da9664a33a494e7e99caafb8d revision: bf3681b5be9a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.761643) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.762067) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.762366) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006219 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.784469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.784751) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.797706) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.797979) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.847127) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.847473) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.847818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.848089) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-11 15:04:58-- 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% 84.7M=0s 2016-02-11 15:04:58 (84.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092408 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.952651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:57.952957) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022518 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.001858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.002196) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bf3681b5be9a --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 bf3681b5be9a --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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-11 15:04:58,201 Setting DEBUG logging. 2016-02-11 15:04:58,201 attempt 1/10 2016-02-11 15:04:58,201 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bf3681b5be9a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-11 15:04:58,436 unpacking tar archive at: mozilla-beta-bf3681b5be9a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.494754 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.509616) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.509960) ========= echo bf3681b5be9a in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'bf3681b5be9a'] 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False bf3681b5be9a program finished with exit code 0 elapsedTime=0.004660 script_repo_revision: 'bf3681b5be9a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.564301) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.564911) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 15:04:58.575596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-02-11 15:04:58.575858) ========= /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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:04:58 INFO - MultiFileLogger online at 20160211 15:04:58 in /builds/slave/test-pgo 15:04:58 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 15:04:58 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:04:58 INFO - {'append_to_log': False, 15:04:58 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:04:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:04:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:04:58 INFO - 'branch': 'Mozilla-Beta', 15:04:58 INFO - 'buildbot_json_path': 'buildprops.json', 15:04:58 INFO - 'config_files': ('talos/linux_config.py',), 15:04:58 INFO - 'default_actions': ('clobber', 15:04:58 INFO - 'read-buildbot-config', 15:04:58 INFO - 'download-and-extract', 15:04:58 INFO - 'populate-webroot', 15:04:58 INFO - 'create-virtualenv', 15:04:58 INFO - 'install', 15:04:58 INFO - 'run-tests'), 15:04:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:04:58 INFO - 'download_minidump_stackwalk': True, 15:04:58 INFO - 'download_symbols': 'ondemand', 15:04:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:04:58 INFO - 'tooltool.py': '/tools/tooltool.py', 15:04:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:04:58 INFO - '/tools/misc-python/virtualenv.py')}, 15:04:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:04:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:04:58 INFO - 'installer_path': 'installer.exe', 15:04:58 INFO - 'log_level': 'info', 15:04:58 INFO - 'log_name': 'talos', 15:04:58 INFO - 'log_to_console': True, 15:04:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:04:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:04:58 INFO - 'opt_config_files': (), 15:04:58 INFO - 'pip_index': False, 15:04:58 INFO - 'sps_profile': False, 15:04:58 INFO - 'sps_profile_interval': 0, 15:04:58 INFO - 'suite': 'dromaeojs', 15:04:58 INFO - 'system_bits': '32', 15:04:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:04:58 INFO - 'title': 'talos-linux64-ix-058', 15:04:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:04:58 INFO - 'use_talos_json': True, 15:04:58 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:04:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:04:58 INFO - 'work_dir': 'build'} 15:04:58 INFO - ##### 15:04:58 INFO - ##### Running clobber step. 15:04:58 INFO - ##### 15:04:58 INFO - Running pre-action listener: _resource_record_pre_action 15:04:58 INFO - Running main action method: clobber 15:04:58 INFO - rmtree: /builds/slave/test-pgo/build 15:04:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:04:59 INFO - Running post-action listener: _resource_record_post_action 15:04:59 INFO - ##### 15:04:59 INFO - ##### Running read-buildbot-config step. 15:04:59 INFO - ##### 15:04:59 INFO - Running pre-action listener: _resource_record_pre_action 15:04:59 INFO - Running main action method: read_buildbot_config 15:04:59 INFO - Using buildbot properties: 15:04:59 INFO - { 15:04:59 INFO - "properties": { 15:04:59 INFO - "buildnumber": 82, 15:04:59 INFO - "product": "firefox", 15:04:59 INFO - "script_repo_revision": "production", 15:04:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:04:59 INFO - "repository": "", 15:04:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:04:59 INFO - "buildid": "20160211125933", 15:04:59 INFO - "slavename": "talos-linux64-ix-058", 15:04:59 INFO - "pgo_build": "True", 15:04:59 INFO - "basedir": "/builds/slave/test-pgo", 15:04:59 INFO - "project": "", 15:04:59 INFO - "platform": "ubuntu64_hw", 15:04:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:04:59 INFO - "slavebuilddir": "test", 15:04:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:04:59 INFO - "branch": "mozilla-beta", 15:04:59 INFO - "repo_path": "releases/mozilla-beta", 15:04:59 INFO - "stage_platform": "linux64-pgo", 15:04:59 INFO - "builduid": "9e924c2da9664a33a494e7e99caafb8d", 15:04:59 INFO - "revision": "bf3681b5be9a" 15:04:59 INFO - }, 15:04:59 INFO - "sourcestamp": { 15:04:59 INFO - "repository": "", 15:04:59 INFO - "hasPatch": false, 15:04:59 INFO - "project": "", 15:04:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:04:59 INFO - "changes": [ 15:04:59 INFO - { 15:04:59 INFO - "category": null, 15:04:59 INFO - "files": [ 15:04:59 INFO - { 15:04:59 INFO - "url": null, 15:04:59 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2" 15:04:59 INFO - } 15:04:59 INFO - ], 15:04:59 INFO - "repository": "", 15:04:59 INFO - "rev": "bf3681b5be9a", 15:04:59 INFO - "who": "sendchange", 15:04:59 INFO - "when": 1455231866, 15:04:59 INFO - "number": 7597135, 15:04:59 INFO - "comments": "Backed out changeset 30cfcf54f286 (bug 1244221) for browser_aboutHome_wrapsCorrectly.js failures a=backout", 15:04:59 INFO - "project": "", 15:04:59 INFO - "at": "Thu 11 Feb 2016 15:04:26", 15:04:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:04:59 INFO - "revlink": "", 15:04:59 INFO - "properties": [ 15:04:59 INFO - [ 15:04:59 INFO - "buildid", 15:04:59 INFO - "20160211125933", 15:04:59 INFO - "Change" 15:04:59 INFO - ], 15:04:59 INFO - [ 15:04:59 INFO - "builduid", 15:04:59 INFO - "9e924c2da9664a33a494e7e99caafb8d", 15:04:59 INFO - "Change" 15:04:59 INFO - ], 15:04:59 INFO - [ 15:04:59 INFO - "pgo_build", 15:04:59 INFO - "True", 15:04:59 INFO - "Change" 15:04:59 INFO - ] 15:04:59 INFO - ], 15:04:59 INFO - "revision": "bf3681b5be9a" 15:04:59 INFO - } 15:04:59 INFO - ], 15:04:59 INFO - "revision": "bf3681b5be9a" 15:04:59 INFO - } 15:04:59 INFO - } 15:04:59 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2. 15:04:59 INFO - Running post-action listener: _resource_record_post_action 15:04:59 INFO - ##### 15:04:59 INFO - ##### Running download-and-extract step. 15:04:59 INFO - ##### 15:04:59 INFO - Running pre-action listener: _resource_record_pre_action 15:04:59 INFO - Running main action method: download_and_extract 15:04:59 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:04:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/test_packages.json 15:04:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:04:59 INFO - Downloaded 1270 bytes. 15:04:59 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:04:59 INFO - Using the following test package requirements: 15:04:59 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:04:59 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:04:59 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'jsshell-linux-x86_64.zip'], 15:04:59 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:04:59 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:04:59 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 15:04:59 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 15:04:59 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:04:59 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:04:59 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:04:59 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:04:59 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:04:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:04:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:05 INFO - Downloaded 21581705 bytes. 15:05:05 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 15:05:05 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:05:05 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 15:05:06 INFO - Return code: 0 15:05:06 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 15:05:06 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')]} 15:05:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:05:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:07 INFO - Downloaded 21581705 bytes. 15:05:07 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 15:05:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:05:07 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 15:05:08 INFO - Return code: 0 15:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 15:05:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:08 INFO - Downloaded 11132038 bytes. 15:05:08 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 15:05:08 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 15:05:08 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 15:05:08 INFO - Return code: 0 15:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:05:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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 15:05:25 INFO - Downloaded 57974562 bytes. 15:05:25 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:05:25 INFO - mkdir: /builds/slave/test-pgo/properties 15:05:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:05:25 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:05:25 INFO - Contents: 15:05:25 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:05:25 INFO - Running post-action listener: _resource_record_post_action 15:05:25 INFO - Running post-action listener: set_extra_try_arguments 15:05:25 INFO - ##### 15:05:25 INFO - ##### Running populate-webroot step. 15:05:25 INFO - ##### 15:05:25 INFO - Running pre-action listener: _resource_record_pre_action 15:05:25 INFO - Running main action method: populate_webroot 15:05:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:05:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:05:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:05:25 INFO - '--tppagecycles', 15:05:25 INFO - '7'], 15:05:25 INFO - 'tests': ['tsvgm']}}, 15:05:25 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:05:25 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:05:25 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:05:25 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:05:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:05:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:05:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'tests': ['damp', 'tps']}, 15:05:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['damp', 'tps']}, 15:05:25 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:05:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:05:25 INFO - 'other': {'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'other_l64': {'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'other_nol64': {'tests': ['a11yr', 15:05:25 INFO - 'ts_paint', 15:05:25 INFO - 'tpaint', 15:05:25 INFO - 'sessionrestore', 15:05:25 INFO - 'sessionrestore_no_auto_restore']}, 15:05:25 INFO - 'svgr': {'tests': ['tsvgx', 15:05:25 INFO - 'tsvgr_opacity', 15:05:25 INFO - 'tart', 15:05:25 INFO - 'tscrollx', 15:05:25 INFO - 'cart']}, 15:05:25 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['tsvgx', 15:05:25 INFO - 'tsvgr_opacity', 15:05:25 INFO - 'tart', 15:05:25 INFO - 'tscrollx', 15:05:25 INFO - 'cart']}, 15:05:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'tests': ['tp5o']}, 15:05:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'talos_options': ['--e10s'], 15:05:25 INFO - 'tests': ['tp5o']}, 15:05:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'talos_options': ['--xperf_path', 15:05:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:05:25 INFO - 'tests': ['tp5n']}, 15:05:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:05:25 INFO - 'talos_options': ['--e10s', 15:05:25 INFO - '--xperf_path', 15:05:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:05:25 INFO - 'tests': ['tp5n']}}, 15:05:25 INFO - 'talos.zip': {'path': '', 15:05:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:05:25 INFO - Running post-action listener: _resource_record_post_action 15:05:25 INFO - ##### 15:05:25 INFO - ##### Running create-virtualenv step. 15:05:25 INFO - ##### 15:05:25 INFO - Running pre-action listener: _resource_record_pre_action 15:05:25 INFO - Running main action method: create_virtualenv 15:05:25 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:05:25 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 15:05:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:05:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:05:25 INFO - Using real prefix '/usr' 15:05:25 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:05:26 INFO - Installing distribute.............................................................................................................................................................................................done. 15:05:28 INFO - Installing pip.................done. 15:05:28 INFO - Return code: 0 15:05:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:28 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 15:05:28 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 15:05:28 INFO - Using env: {'DISPLAY': ':0', 15:05:28 INFO - 'HOME': '/home/cltbld', 15:05:28 INFO - 'LANG': 'en_US.UTF-8', 15:05:28 INFO - 'LANGUAGE': 'en_US:en', 15:05:28 INFO - 'LOGNAME': 'cltbld', 15:05:28 INFO - 'MAIL': '/var/mail/cltbld', 15:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:28 INFO - 'MOZ_NO_REMOTE': '1', 15:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:28 INFO - 'NO_EM_RESTART': '1', 15:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:28 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:28 INFO - 'SHELL': '/bin/bash', 15:05:28 INFO - 'SHLVL': '1', 15:05:28 INFO - 'TERM': 'linux', 15:05:28 INFO - 'TMOUT': '86400', 15:05:28 INFO - 'USER': 'cltbld', 15:05:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:28 INFO - '_': '/tools/buildbot/bin/python'} 15:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:28 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 15:05:28 INFO - Cleaning up... 15:05:28 INFO - Return code: 0 15:05:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:28 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:28 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 15:05:28 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 15:05:28 INFO - Using env: {'DISPLAY': ':0', 15:05:28 INFO - 'HOME': '/home/cltbld', 15:05:28 INFO - 'LANG': 'en_US.UTF-8', 15:05:28 INFO - 'LANGUAGE': 'en_US:en', 15:05:28 INFO - 'LOGNAME': 'cltbld', 15:05:28 INFO - 'MAIL': '/var/mail/cltbld', 15:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:28 INFO - 'MOZ_NO_REMOTE': '1', 15:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:28 INFO - 'NO_EM_RESTART': '1', 15:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:28 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:28 INFO - 'SHELL': '/bin/bash', 15:05:28 INFO - 'SHLVL': '1', 15:05:28 INFO - 'TERM': 'linux', 15:05:28 INFO - 'TMOUT': '86400', 15:05:28 INFO - 'USER': 'cltbld', 15:05:28 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:28 INFO - '_': '/tools/buildbot/bin/python'} 15:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:28 INFO - Downloading/unpacking psutil>=0.7.1 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:28 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 15:05:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:05:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:05:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:05:30 INFO - Installing collected packages: psutil 15:05:30 INFO - Running setup.py install for psutil 15:05:30 INFO - building 'psutil._psutil_linux' extension 15:05:30 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 15:05:30 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 15:05:30 INFO - building 'psutil._psutil_posix' extension 15:05:30 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 15:05:31 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 15:05:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:05:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:05:31 INFO - Successfully installed psutil 15:05:31 INFO - Cleaning up... 15:05:31 INFO - Return code: 0 15:05:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:05:31 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')]} 15:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:31 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')]} 15:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:31 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:31 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 15:05:31 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 15:05:31 INFO - Using env: {'DISPLAY': ':0', 15:05:31 INFO - 'HOME': '/home/cltbld', 15:05:31 INFO - 'LANG': 'en_US.UTF-8', 15:05:31 INFO - 'LANGUAGE': 'en_US:en', 15:05:31 INFO - 'LOGNAME': 'cltbld', 15:05:31 INFO - 'MAIL': '/var/mail/cltbld', 15:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:31 INFO - 'MOZ_NO_REMOTE': '1', 15:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:31 INFO - 'NO_EM_RESTART': '1', 15:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:31 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:31 INFO - 'SHELL': '/bin/bash', 15:05:31 INFO - 'SHLVL': '1', 15:05:31 INFO - 'TERM': 'linux', 15:05:31 INFO - 'TMOUT': '86400', 15:05:31 INFO - 'USER': 'cltbld', 15:05:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:31 INFO - '_': '/tools/buildbot/bin/python'} 15:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:31 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 15:05:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:05:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:05:33 INFO - Installing collected packages: mozsystemmonitor 15:05:33 INFO - Running setup.py install for mozsystemmonitor 15:05:33 INFO - Successfully installed mozsystemmonitor 15:05:33 INFO - Cleaning up... 15:05:33 INFO - Return code: 0 15:05:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:05:33 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')]} 15:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:33 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')]} 15:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:33 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:33 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 15:05:33 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 15:05:33 INFO - Using env: {'DISPLAY': ':0', 15:05:33 INFO - 'HOME': '/home/cltbld', 15:05:33 INFO - 'LANG': 'en_US.UTF-8', 15:05:33 INFO - 'LANGUAGE': 'en_US:en', 15:05:33 INFO - 'LOGNAME': 'cltbld', 15:05:33 INFO - 'MAIL': '/var/mail/cltbld', 15:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:33 INFO - 'MOZ_NO_REMOTE': '1', 15:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:33 INFO - 'NO_EM_RESTART': '1', 15:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:33 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:33 INFO - 'SHELL': '/bin/bash', 15:05:33 INFO - 'SHLVL': '1', 15:05:33 INFO - 'TERM': 'linux', 15:05:33 INFO - 'TMOUT': '86400', 15:05:33 INFO - 'USER': 'cltbld', 15:05:33 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:33 INFO - '_': '/tools/buildbot/bin/python'} 15:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:33 INFO - Downloading/unpacking blobuploader==1.2.4 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:33 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 15:05:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:05:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:05:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:05: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 15:05: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 15:05: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 15:05: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 15:05: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 15:05: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 15:05:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:05:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:05: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 15:05: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 15:05: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 15:05: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 15:05: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 15:05: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 15:05:36 INFO - Downloading docopt-0.6.1.tar.gz 15:05:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:05:36 INFO - Installing collected packages: blobuploader, requests, docopt 15:05:36 INFO - Running setup.py install for blobuploader 15:05:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:05:36 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:05:36 INFO - Running setup.py install for requests 15:05:36 INFO - Running setup.py install for docopt 15:05:36 INFO - Successfully installed blobuploader requests docopt 15:05:36 INFO - Cleaning up... 15:05:36 INFO - Return code: 0 15:05:36 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:36 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:36 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 15:05:36 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 15:05:36 INFO - Using env: {'DISPLAY': ':0', 15:05:36 INFO - 'HOME': '/home/cltbld', 15:05:36 INFO - 'LANG': 'en_US.UTF-8', 15:05:36 INFO - 'LANGUAGE': 'en_US:en', 15:05:36 INFO - 'LOGNAME': 'cltbld', 15:05:36 INFO - 'MAIL': '/var/mail/cltbld', 15:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:36 INFO - 'MOZ_NO_REMOTE': '1', 15:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:36 INFO - 'NO_EM_RESTART': '1', 15:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:36 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:36 INFO - 'SHELL': '/bin/bash', 15:05:36 INFO - 'SHLVL': '1', 15:05:36 INFO - 'TERM': 'linux', 15:05:36 INFO - 'TMOUT': '86400', 15:05:36 INFO - 'USER': 'cltbld', 15:05:36 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:36 INFO - '_': '/tools/buildbot/bin/python'} 15:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:05:36 INFO - Running setup.py (path:/tmp/pip-2pM8P_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:05:37 INFO - Running setup.py (path:/tmp/pip-CzjJGx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:05:37 INFO - Running setup.py (path:/tmp/pip-1mJknF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:05:37 INFO - Running setup.py (path:/tmp/pip-cz7NiC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:05:37 INFO - Running setup.py (path:/tmp/pip-pLO3ub-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:05:37 INFO - Running setup.py (path:/tmp/pip-qHIP8m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:05:37 INFO - Running setup.py (path:/tmp/pip-emadRk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:05:37 INFO - Running setup.py (path:/tmp/pip-sEMFVj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:05:37 INFO - Running setup.py (path:/tmp/pip-tqnEwZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:05:37 INFO - Running setup.py (path:/tmp/pip-Wo_Zc4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:05:37 INFO - Running setup.py (path:/tmp/pip-PDTR6G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:05:37 INFO - Running setup.py (path:/tmp/pip-g7aqYs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:05:37 INFO - Running setup.py (path:/tmp/pip-qSvLaZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:05:37 INFO - Running setup.py (path:/tmp/pip-CsCpZJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:05:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:05:37 INFO - Running setup.py (path:/tmp/pip-_GymQO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:05:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:05:38 INFO - Running setup.py (path:/tmp/pip-FgDpHm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:05:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:05:38 INFO - Running setup.py (path:/tmp/pip-aHNuJc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:05:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:05:38 INFO - Running setup.py install for manifestparser 15:05:38 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Running setup.py install for mozcrash 15:05:38 INFO - Running setup.py install for mozdebug 15:05:38 INFO - Running setup.py install for mozdevice 15:05:38 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Running setup.py install for mozfile 15:05:38 INFO - Running setup.py install for mozhttpd 15:05:38 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Running setup.py install for mozinfo 15:05:38 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Running setup.py install for mozInstall 15:05:38 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:05:38 INFO - Running setup.py install for mozleak 15:05:39 INFO - Running setup.py install for mozlog 15:05:39 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Running setup.py install for moznetwork 15:05:39 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Running setup.py install for mozprocess 15:05:39 INFO - Running setup.py install for mozprofile 15:05:39 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Running setup.py install for mozrunner 15:05:39 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Running setup.py install for mozscreenshot 15:05:39 INFO - Running setup.py install for moztest 15:05:39 INFO - Running setup.py install for mozversion 15:05:39 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:05:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:05:39 INFO - Cleaning up... 15:05:39 INFO - Return code: 0 15:05:39 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:39 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:39 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 15:05:39 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 15:05:39 INFO - Using env: {'DISPLAY': ':0', 15:05:39 INFO - 'HOME': '/home/cltbld', 15:05:39 INFO - 'LANG': 'en_US.UTF-8', 15:05:39 INFO - 'LANGUAGE': 'en_US:en', 15:05:39 INFO - 'LOGNAME': 'cltbld', 15:05:39 INFO - 'MAIL': '/var/mail/cltbld', 15:05:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:39 INFO - 'MOZ_NO_REMOTE': '1', 15:05:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:39 INFO - 'NO_EM_RESTART': '1', 15:05:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:39 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:39 INFO - 'SHELL': '/bin/bash', 15:05:39 INFO - 'SHLVL': '1', 15:05:39 INFO - 'TERM': 'linux', 15:05:39 INFO - 'TMOUT': '86400', 15:05:39 INFO - 'USER': 'cltbld', 15:05:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:39 INFO - '_': '/tools/buildbot/bin/python'} 15:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:05:40 INFO - Running setup.py (path:/tmp/pip-MANElq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:05:40 INFO - Running setup.py (path:/tmp/pip-X0CpeG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:05:40 INFO - Running setup.py (path:/tmp/pip-dnsGwC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:05:40 INFO - Running setup.py (path:/tmp/pip-cQhpvX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:05:40 INFO - Running setup.py (path:/tmp/pip-16DpEZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:05:40 INFO - Running setup.py (path:/tmp/pip-I2Ki_3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:05:40 INFO - Running setup.py (path:/tmp/pip-cH297T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:05:40 INFO - Running setup.py (path:/tmp/pip-muZjuu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:05:40 INFO - Running setup.py (path:/tmp/pip-vnF9FX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:05:40 INFO - Running setup.py (path:/tmp/pip-BJRXLF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:05:40 INFO - Running setup.py (path:/tmp/pip-KeiLLw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:05:40 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)) 15:05:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:05:40 INFO - Running setup.py (path:/tmp/pip-A1Htwj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:05:41 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)) 15:05:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:05:41 INFO - Running setup.py (path:/tmp/pip-sHSl0_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:05:41 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)) 15:05:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:05:41 INFO - Running setup.py (path:/tmp/pip-KEiX3o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:05:41 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)) 15:05:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:05:41 INFO - Running setup.py (path:/tmp/pip-hg_K9U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:05:41 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)) 15:05:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:05:41 INFO - Running setup.py (path:/tmp/pip-Aweei5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:05:41 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)) 15:05:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:05:41 INFO - Running setup.py (path:/tmp/pip-W3K4E5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:05:41 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)) 15:05:41 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)) 15:05:41 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)) 15:05:41 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)) 15:05:41 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)) 15:05:41 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)) 15:05:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:05:41 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 15:05:41 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 15:05:41 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 15:05:41 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 15:05:41 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 15:05:41 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 15:05:43 INFO - Downloading blessings-1.6.tar.gz 15:05:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:05:43 INFO - Installing collected packages: blessings 15:05:43 INFO - Running setup.py install for blessings 15:05:43 INFO - Successfully installed blessings 15:05:43 INFO - Cleaning up... 15:05:43 INFO - Return code: 0 15:05:43 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:05:43 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:05:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:05:44 INFO - Reading from file tmpfile_stdout 15:05:44 INFO - Current package versions: 15:05:44 INFO - argparse == 1.2.1 15:05:44 INFO - blessings == 1.6 15:05:44 INFO - blobuploader == 1.2.4 15:05:44 INFO - docopt == 0.6.1 15:05:44 INFO - manifestparser == 1.1 15:05:44 INFO - mozInstall == 1.12 15:05:44 INFO - mozcrash == 0.16 15:05:44 INFO - mozdebug == 0.1 15:05:44 INFO - mozdevice == 0.47 15:05:44 INFO - mozfile == 1.2 15:05:44 INFO - mozhttpd == 0.7 15:05:44 INFO - mozinfo == 0.9 15:05:44 INFO - mozleak == 0.1 15:05:44 INFO - mozlog == 3.1 15:05:44 INFO - moznetwork == 0.27 15:05:44 INFO - mozprocess == 0.22 15:05:44 INFO - mozprofile == 0.27 15:05:44 INFO - mozrunner == 6.11 15:05:44 INFO - mozscreenshot == 0.1 15:05:44 INFO - mozsystemmonitor == 0.0 15:05:44 INFO - moztest == 0.7 15:05:44 INFO - mozversion == 1.4 15:05:44 INFO - psutil == 3.1.1 15:05:44 INFO - requests == 1.2.3 15:05:44 INFO - wsgiref == 0.1.2 15:05:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 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 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:44 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 15:05:44 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 15:05:44 INFO - Using env: {'DISPLAY': ':0', 15:05:44 INFO - 'HOME': '/home/cltbld', 15:05:44 INFO - 'LANG': 'en_US.UTF-8', 15:05:44 INFO - 'LANGUAGE': 'en_US:en', 15:05:44 INFO - 'LOGNAME': 'cltbld', 15:05:44 INFO - 'MAIL': '/var/mail/cltbld', 15:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:44 INFO - 'MOZ_NO_REMOTE': '1', 15:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:44 INFO - 'NO_EM_RESTART': '1', 15:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:44 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:44 INFO - 'SHELL': '/bin/bash', 15:05:44 INFO - 'SHLVL': '1', 15:05:44 INFO - 'TERM': 'linux', 15:05:44 INFO - 'TMOUT': '86400', 15:05:44 INFO - 'USER': 'cltbld', 15:05:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:44 INFO - '_': '/tools/buildbot/bin/python'} 15:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 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)) 15:05:44 INFO - Cleaning up... 15:05:44 INFO - Return code: 0 15:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 15:05:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:05:44 INFO - Using env: {'DISPLAY': ':0', 15:05:44 INFO - 'HOME': '/home/cltbld', 15:05:44 INFO - 'LANG': 'en_US.UTF-8', 15:05:44 INFO - 'LANGUAGE': 'en_US:en', 15:05:44 INFO - 'LOGNAME': 'cltbld', 15:05:44 INFO - 'MAIL': '/var/mail/cltbld', 15:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:44 INFO - 'MOZ_NO_REMOTE': '1', 15:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:44 INFO - 'NO_EM_RESTART': '1', 15:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:44 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:44 INFO - 'SHELL': '/bin/bash', 15:05:44 INFO - 'SHLVL': '1', 15:05:44 INFO - 'TERM': 'linux', 15:05:44 INFO - 'TMOUT': '86400', 15:05:44 INFO - 'USER': 'cltbld', 15:05:44 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:44 INFO - '_': '/tools/buildbot/bin/python'} 15:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:44 INFO - Downloading/unpacking jsonschema 15:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:05:46 INFO - Downloading/unpacking functools32 (from jsonschema) 15:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:46 INFO - Downloading functools32-3.2.3-2.tar.gz 15:05:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:05:46 INFO - warning: no files found matching '*.txt' 15:05:46 INFO - no previously-included directories found matching 'build' 15:05:46 INFO - no previously-included directories found matching 'dist' 15:05:46 INFO - no previously-included directories found matching '.git*' 15:05:46 INFO - Installing collected packages: jsonschema, functools32 15:05:46 INFO - Running setup.py install for functools32 15:05:46 INFO - warning: no files found matching '*.txt' 15:05:46 INFO - no previously-included directories found matching 'build' 15:05:46 INFO - no previously-included directories found matching 'dist' 15:05:46 INFO - no previously-included directories found matching '.git*' 15:05:46 INFO - Successfully installed jsonschema functools32 15:05:46 INFO - Cleaning up... 15:05:46 INFO - Return code: 0 15:05:46 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:05:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:05:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:05:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7121143f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27d6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, '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': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', '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 15:05:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 15:05:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:05:46 INFO - Using env: {'DISPLAY': ':0', 15:05:46 INFO - 'HOME': '/home/cltbld', 15:05:46 INFO - 'LANG': 'en_US.UTF-8', 15:05:46 INFO - 'LANGUAGE': 'en_US:en', 15:05:46 INFO - 'LOGNAME': 'cltbld', 15:05:46 INFO - 'MAIL': '/var/mail/cltbld', 15:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:05:46 INFO - 'MOZ_NO_REMOTE': '1', 15:05:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:05:46 INFO - 'NO_EM_RESTART': '1', 15:05:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:05:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:05:46 INFO - 'PWD': '/builds/slave/test-pgo', 15:05:46 INFO - 'SHELL': '/bin/bash', 15:05:46 INFO - 'SHLVL': '1', 15:05:46 INFO - 'TERM': 'linux', 15:05:46 INFO - 'TMOUT': '86400', 15:05:46 INFO - 'USER': 'cltbld', 15:05:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:05:46 INFO - '_': '/tools/buildbot/bin/python'} 15:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:05:47 INFO - Downloading/unpacking flake8 15:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:05:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:05:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:05:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:05:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:05:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:05:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:05:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:05:49 INFO - no previously-included directories found matching 'docs/_build' 15:05:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:05:49 INFO - Running setup.py install for pep8 15:05:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:05:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:05:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:05:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:05:49 INFO - no previously-included directories found matching 'docs/_build' 15:05:49 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:05:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:05:49 INFO - Cleaning up... 15:05:49 INFO - Return code: 0 15:05:49 INFO - Running post-action listener: _resource_record_post_action 15:05:49 INFO - Running post-action listener: _start_resource_monitoring 15:05:49 INFO - Starting resource monitoring. 15:05:49 INFO - ##### 15:05:49 INFO - ##### Running install step. 15:05:49 INFO - ##### 15:05:49 INFO - Running pre-action listener: _resource_record_pre_action 15:05:49 INFO - Running main action method: install 15:05:49 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:05:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:05:50 INFO - Reading from file tmpfile_stdout 15:05:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:05:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:05:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:05:50 INFO - Reading from file tmpfile_stdout 15:05:50 INFO - Output received: 15:05:50 INFO - Usage: mozinstall [options] installer 15:05:50 INFO - Options: 15:05:50 INFO - -h, --help show this help message and exit 15:05:50 INFO - -d DEST, --destination=DEST 15:05:50 INFO - Directory to install application into. [default: 15:05:50 INFO - "/builds/slave/test-pgo"] 15:05:50 INFO - --app=APP Application being installed. [default: firefox] 15:05:50 INFO - mkdir: /builds/slave/test-pgo/build/application 15:05:50 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'] 15:05:50 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 15:06:04 INFO - Reading from file tmpfile_stdout 15:06:04 INFO - Output received: 15:06:04 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:06:04 INFO - Running post-action listener: _resource_record_post_action 15:06:04 INFO - ##### 15:06:04 INFO - ##### Running run-tests step. 15:06:04 INFO - ##### 15:06:04 INFO - Running pre-action listener: _resource_record_pre_action 15:06:04 INFO - Running main action method: run_tests 15:06:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:06:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:06:04 INFO - Python 2.7.3 15:06:04 INFO - Return code: 0 15:06:04 INFO - grabbing minidump binary from tooltool 15:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:06:04 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 0x2865350>, '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 0x2866250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28666e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:06:04 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 15:06:04 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 15:06:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:06:04 INFO - Return code: 0 15:06:04 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:06:04 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:06:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:06:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:06:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:06:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:06:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:06:05 INFO - Return code: 0 15:06:05 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/1455224373/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:06:05 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/1455224373/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost 15:06:05 INFO - Using env: {'DISPLAY': ':0', 15:06:05 INFO - 'HOME': '/home/cltbld', 15:06:05 INFO - 'LANG': 'en_US.UTF-8', 15:06:05 INFO - 'LANGUAGE': 'en_US:en', 15:06:05 INFO - 'LOGNAME': 'cltbld', 15:06:05 INFO - 'MAIL': '/var/mail/cltbld', 15:06:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:06:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:06:05 INFO - 'MOZ_NO_REMOTE': '1', 15:06:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:06:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:06:05 INFO - 'NO_EM_RESTART': '1', 15:06:05 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:06:05 INFO - 'PWD': '/builds/slave/test-pgo', 15:06:05 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:06:05 INFO - 'SHELL': '/bin/bash', 15:06:05 INFO - 'SHLVL': '1', 15:06:05 INFO - 'TERM': 'linux', 15:06:05 INFO - 'TMOUT': '86400', 15:06:05 INFO - 'USER': 'cltbld', 15:06:05 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392', 15:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:06:05 INFO - '_': '/tools/buildbot/bin/python'} 15:06:05 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/1455224373/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost'] with output_timeout 3600 15:06:06 INFO - mozversion INFO | application_buildid: 20160211125933 15:06:06 INFO - mozversion INFO | application_changeset: bf3681b5be9a4fbeb525475e8197833b0bc20b8b 15:06:06 INFO - mozversion INFO | application_display_name: Nightly 15:06:06 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:06:06 INFO - mozversion INFO | application_name: Firefox 15:06:06 INFO - mozversion INFO | application_remotingname: firefox 15:06:06 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:06:06 INFO - mozversion INFO | application_vendor: Mozilla 15:06:06 INFO - mozversion INFO | application_version: 45.0 15:06:06 INFO - mozversion INFO | platform_buildid: 20160211125933 15:06:06 INFO - mozversion INFO | platform_changeset: bf3681b5be9a4fbeb525475e8197833b0bc20b8b 15:06:06 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:06:06 INFO - mozversion INFO | platform_version: 45.0 15:06:06 INFO - 2016-02-11 15:06:06,041 DEBUG : using testdate: 1455231966 15:06:06 INFO - 2016-02-11 15:06:06,041 DEBUG : actual date: 1455231966 15:06:06 INFO - 2016-02-11 15:06:06,050 INFO : starting webserver on 'localhost:37404' 15:06:06 INFO - 2016-02-11 15:06:06,050 INFO : Starting test suite talos-linux64-ix-058 15:06:06 INFO - 2016-02-11 15:06:06,051 INFO : Starting test dromaeo_css 15:06:06 INFO - 2016-02-11 15:06:06,051 DEBUG : operating with platform_type : linux_ 15:06:06 INFO - 2016-02-11 15:06:06,051 INFO : Initialising browser for dromaeo_css test... 15:06:06 INFO - 2016-02-11 15:06:06,067 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUD2HRU/profile http://localhost:37404/getInfo.html 15:06:09 INFO - 2016-02-11 15:06:09,499 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:06:09 INFO - 2016-02-11 15:06:09,500 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:06:09 INFO - 2016-02-11 15:06:09,500 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:06:09 INFO - 2016-02-11 15:06:09,500 DEBUG : BROWSER_OUTPUT: __metrics 15:06:09 INFO - 2016-02-11 15:06:09,709 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] 15:06:10 INFO - 2016-02-11 15:06:10,746 INFO : Browser initialized. 15:06:10 INFO - 2016-02-11 15:06:10,746 INFO : Running cycle 1/1 for dromaeo_css test... 15:06:10 INFO - 2016-02-11 15:06:10,747 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUD2HRU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:06:22 INFO - 2016-02-11 15:06:22,408 DEBUG : BROWSER_OUTPUT: RSS: Main: 134205440 15:06:22 INFO - 2016-02-11 15:06:22,409 DEBUG : BROWSER_OUTPUT: 15:07:13 INFO - 2016-02-11 15:07:13,273 DEBUG : BROWSER_OUTPUT: 1455232033265 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] 15:09:21 INFO - 2016-02-11 15:09:21,307 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37404/tests/dromaeo/cssquery-ext.html) 15:09:22 INFO - 2016-02-11 15:09:22,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 244379648 15:09:22 INFO - 2016-02-11 15:09:22,386 DEBUG : BROWSER_OUTPUT: 15:12:24 INFO - 2016-02-11 15:12:24,440 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-ext.html (next: http://localhost:37404/tests/dromaeo/cssquery-jquery.html) 15:12:25 INFO - 2016-02-11 15:12:25,002 DEBUG : BROWSER_OUTPUT: RSS: Main: 213000192 15:12:25 INFO - 2016-02-11 15:12:25,003 DEBUG : BROWSER_OUTPUT: 15:15:26 INFO - 2016-02-11 15:15:26,668 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37404/tests/dromaeo/cssquery-mootools.html) 15:15:27 INFO - 2016-02-11 15:15:27,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 214372352 15:15:27 INFO - 2016-02-11 15:15:27,229 DEBUG : BROWSER_OUTPUT: 15:18:28 INFO - 2016-02-11 15:18:28,919 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37404/tests/dromaeo/cssquery-prototype.html) 15:18:29 INFO - 2016-02-11 15:18:29,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 197451776 15:18:29 INFO - 2016-02-11 15:18:29,480 DEBUG : BROWSER_OUTPUT: 15:21:31 INFO - 2016-02-11 15:21:31,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37404/tests/dromaeo/cssquery-yui.html) 15:21:32 INFO - 2016-02-11 15:21:32,391 DEBUG : BROWSER_OUTPUT: RSS: Main: 197775360 15:21:32 INFO - 2016-02-11 15:21:32,391 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,464 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/dromaeo/cssquery-yui.html (next: http://localhost:37404/tests/dromaeo/cssquery-dojo.html) 15:24:34 INFO - 2016-02-11 15:24:34,715 DEBUG : BROWSER_OUTPUT: RSS: Main: 241176576 15:24:34 INFO - 2016-02-11 15:24:34,715 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,716 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:24:34 INFO - 2016-02-11 15:24:34,716 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:24:34 INFO - 2016-02-11 15:24:34,716 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:24:34 INFO - 2016-02-11 15:24:34,716 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:24:34 INFO - 2016-02-11 15:24:34,718 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3274.2632907595785;3378.493226016099;3411.9317613647727;3412.5051867479215;3431.8798842040524;5533.557315414767;5633.324001119866;5635.239242702234;5637.379888212294;5651.039323315036;5619.438056194378;5672.432756724325;5678.148277758339;5678.886422271552;5683.886322273552;4902.509749025086;5115.590752739781;5150.304708864312;5193.298904647881;5238.214267859832;4998.5501304882555;5081.771320290596;5084.13569693153;5093.592512598983;5148.459411761767;5135;5145.768440420171;5161.741912904358;5163.509466600667;5198.09033419152;4891.951080489185;5086.79652813887;5092.337996060525;5152.587792976553;5152.793888244484;4992.950070499285;5020.297158397827;5089.287499750038;5098.260752190911;5113.693178409308;5345.33183352081;5353.304070470853;5361.276227709248;5374.7850085996515;5399;5759.856003599902;5874;5949.46454819064;5954.910676339859;5965.731542080617;5867.76528938842;5943.3165186003735;5961.970190149022;5996.880062398771;5998.310194327664;5628.324601047819;5630.042892708249;5630.465105814982;5631.042722737145;5632.464915832989;5883.470487656132;5972.462478376967;5980.132880732356;6057.575969682168;6122.081687746873;5955.702214889325;5959.761609535657;5960.701964901824;5965.492933100735;5981.222441082718;6037.72830222641;6042.758289668452;6043.969780151071;6045.153678484928;6046.365131661112;5777.422257774189;5879.265091863517;5907.4683278505145;5991.670458124845;5993.310769261503;5968.880622387528;5974.820755377345;5997.790077347272;6006.519478441802;6013;4581.79381927814;4584.17484852723;4584.33527138563;4587.38070360497;4613.930791038137;4041.312976793893;4079.3676980068135;4128.1330920507025;4144.606262405066;4160.729552579133;5729.570282228766;5734;5734.197212390269;5738.913916291259;5753.021986262345;5964.970175149097;6032.577719559677;6040.244969378828;6040.426159514839;6047.758089676452;5539.446055394428;5631.690257035821;5638.464345887134;5708.71456427177;5757.827265182052;5220.504052115043;5265.842024739264;5271.288376069258;5285.68285902847;5293.0472514946905;6042.546808989343;6270.9372906271265;6281.466075383552;6301.086342480315;6301.6219026858535;5946.316173640087;5998.220231369894;6112.147481619707;6117.062412996569;6119.966902931789;6232.657203853832;6234.501239900887;6235.594686345373;6237.844053898507;6239.469645080219;5760.193572899713;5857.7949771757785;5974.790882319098;5978.730957106854;5979.671118088373;5825.912611310749;5910.261217347832;5913.05391137416;5918.881622367615;5941.613795103304;5916.053431450947;5962.314333851489;5963.105534169736;5963.970180149245;5965.075413310944;5627.099664053731;5759.04975679025;5844.94155058444;5908.084246941731;5964.970175149097;5721.1990321354215;5722.084466485342;5724.713764311851;5725.19847221381;5755.539556835527;5837.153612726216;5856.12158176277;5857;5887.499562537232;5889.499392551681;5449.836504904701;5571.13647384656;5584.246126772834;5703.116017017369;5740.741666625096 15:24:34 INFO - 2016-02-11 15:24:34,718 DEBUG : BROWSER_OUTPUT: |1;ext.html;601.5819005790975;633.3792882974685;644.6744394080988;645.2643985856123;650.2814390098938;33690.49464258038;41604.58395416045;41963.58036419642;41975.370369444485;42024.36963445551;45050.774746126226;45555.31667024997;45570.77214613922;45626.08747825042;45644;8194.09864914859;8292.253697167254;8297.253247207751;9205.447673139599;9398.154166125047;11025.173112016591;11263.155263355282;11267.718307042349;11274.774504509905;11286.661400157967;10824.458777061154;10875.347479151236;10894.291871028357;10949.671509854717;10953.123750100012;10828.945855270713;10852.403117828517;10932.398718070503;10936;10950.452477376139;10688.14494840411;10848.349099054003;10873.347599144077;10882.23824332297;10885.23803333767;43020;43274.350884736756;43361.56638433607;43433.56566434327;43440.56559434397;3336.9833150834093;3355.580552430946;3412.146963259185;3486.7733597316346;3570.107473131717;33430;33698.1575460613;33798;33865;33899;3895.61043895611;4099.713020088595;4110.753354798691;4234.6823988201;4256.191323648497;297211;298475;298840;299630;300237;56349;57580.13629795556;62847.05729414063;63790.36209637937;67629.66185169043;63266.36733632697;63292;63598.36401636017;63672;63748;16545.25546350417;16650.75023874643;16732.24704888283;16756;16807.075610841275;13308.866911330762;13351.065425420322;13372;13404.262765547794;13430.0598958072;20612.484687882923;21009.26467573658;21044.579108417747;21060.36818895405;21062.368128956157;7643.885341719767;7820.452568320163;7832.334251588679;7939.325157361689;7964.362850971908;1024.7386916336286;1096.5120521367915;1155.6767501211066;1180.5690922813112;1210.0138387214392;30502.389952201276;30586.694133058383;31067.067987959945;31094;31276.84361578223;1089.1831126655009;1101.1576144249655;1112.7273817914502;1163.0230606290756;1189.542026319873;13180;13274.668133296746;13291.867081329063;13711.794323085162;15934.362625495081;350252;351227;351540;352435;352880;54674.179887301725;54925.45074549283;54944.45055549393;54946.450535494136;54991.725041374535;1273.9108262421541;1347.0435990446697;1436.3751767980962;1450.0864455393034;1472.138798802691;28333.858330708215;29096.854515727286;29573.408531829675;29632.259193520335;30199.547006795354;46122.53877461317;46314.53685463102;46488;46494.767526162155;46517;231807;232047;232195;232274;232346;33146.83426582852;33189.668103318654;33352.83323583366;33402.66597333996;33605.15987100244;41209.58790412058;41740.373894392214;41973;42284.36573451462;42421.363679544214;36283.27433451264;38165.80917095508;38697.6130238694;38772.41841372438;41354.793226033675;37041.814790926954;37820;38385.808070959465;38623.03442413962;38713.61286387213;38272.61727382691;38530.6146938527;38685;39010.6098939007;39259.214815704094;30567.54148687816;31938.36123277568;34551;34759.13102172466;34910;26467.205983820517;26829.195124146307;26955;27012.45975080527;27180.184594462196 15:24:34 INFO - 2016-02-11 15:24:34,719 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.54698229580237;385.59705108161967;387.17338482340193;387.48271058137396;389.3653345047572;15234.54296371108;16754.83245167548;17244.310227590897;18622.81377186228;18642.720359194624;16108.436204732836;16298.592535186626;16807.411740589083;18506.53733656656;18540.814591854112;6218.284897236819;6342.587731797442;6356.936430635681;6361.236651601815;6364.7135878885565;4710.364100846393;4830.227163653816;6292.622442653433;6983.441324694038;7187.137543494788;6968.512204145712;7079.327463890922;7108;7112.751053713121;7167.139943206823;7209.639518024078;7223.133224013126;7236.167840698335;7275.9636201819185;7278.235785242527;6803.727850885959;6930.826729331807;6948.826279343057;6954.547954382949;6962.338577835098;22814.65778013331;23290.417739556477;23557.646635300483;23561.882190588938;24215.757842421703;2349.3186975777003;2432.4405386761146;2540.8602526861014;2702.6216329713857;2703.4863375958503;14801.703965920622;14844.183569903651;14895.851041489555;15045.699086018329;15191.924040379838;2101.9159233630635;2132.1897678881924;2182.2798476502876;2216.767239439852;2223.8665680059084;69244;71811.64094179496;73566.63216683936;74053;74942;15608.765868511982;16232.837671623369;16233.756493652605;16239.675206495804;16241.187940602922;24308;24308.513829723306;24436.511269774506;24641.753582464306;24673.383165421008;9523.952380238054;9580.856287155699;9590.856137157949;9668.516574171263;9671.081247281498;7803.7268695595385;8156.83686326282;8177.170366833766;8180.550069746102;8274.338052955749;7275.163356213997;8120.147384524658;8182.388496659047;8237.670493180325;8324.833503329899;1915.9712604310946;1926.489480287725;1945.6206039822098;1961.4311849563753;2403.9542798882544;937.8686983822271;942.3921570586933;1003.9498025098716;1067.199600299775;1072.0940805019902;14792.334344954503;15585.610359740871;16697.749533757004;17321.220545075248;18088.186031628607;954.7183580843667;1009.2783659683362;1026.2303272545591;1076.05844885725;1096.6984079378076;10214.846777298346;10400.739981500523;10457.790844183073;10516.68449946503;10538.789224215472;554690;558406;558860;569582;577787;83852.58073709592;85003;85101.57449212838;85698;88045.55977220072;1167.7139100920328;1172.279048385237;1175.7648470305974;1188.2751521571834;1191.0828661930439;10802.243842931233;10863.565457381615;11021.669349919515;16432.424865129662;16633;35995.82002089973;36231;37467.2506549862;39509;39613;46955.29567056429;47419.76290118528;47636.761816190694;47711.28433073576;50226.74886625543;15786.605334866721;16083.75874361862;16169.919150404172;16314.265858036655;16418.343266269694;19950.600987980448;20365.287214947406;20371.59256814885;20476;20490.590188195856;15042.473513426978;15179.316930738365;15405.76891346653;16749.66500670004;16917.91541042287;15494.302756375764;15704.057756534645;16509.17454127313;16667.66664666724;17045.829541704425;16152.353905843634;16659.16704164811;17296.481105566854;17539;17545.210465528828;15793;15795.447159349367;15963.840361596236;16035.919820400823;16048.759268611213;16438.260278287267;16523.421680241136;16690.24893879754;16939.23773430223;16979.49061528156 15:24:34 INFO - 2016-02-11 15:24:34,720 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1529.0443472829481;1531.8314985351617;1707.1037705204758;1797.1194114883706;1810.8501101800362;7336.3397294243505;7718.768436946893;7724.266194711493;7726.459147859652;7729;6713.227978782442;6877.140357455318;7280.890786638205;7324.08448943882;7541.811454713635;2207.2274703853645;2208.712867327253;2235.1729859951843;2239.675247089171;2263.592553340398;2028.6652702304102;2291.5073259249257;2319.8724070176136;2330.8135349172026;2358.1157066100127;2082.312836763872;2288.9313320600327;2328.2316835444262;2328.499372634883;2329.0218108394515;2242.439390152462;2251.313349428424;2257.209976508217;2315.988420057906;2320.9651855222187;2262.162999690125;2275.7041584594062;2294.5066810635626;2294.8278879084137;2346.6597343385115;7523.360514356286;7600.201978792203;7603.847923041508;7609.695612175562;7615.124260710034;4547.136044151601;4639.025804581042;4642.234031384851;4642.814287428532;4642.86071417858;2917;3006.699330066998;3011.2471882029495;3022.440848443045;3033.074912151793;966.9226461883103;969.60730903984;979.4759803505161;998.4308943901979;1016.9237307201989;10191.541380637815;10202.438865862374;10207.795844083152;10215.02957219063;10228;4110.219058378899;4143.7720925349395;4163.812628431728;4261.765602891809;4266.4240327556;8845.027047024822;8846.292296616255;8849.20357167858;8853.380263381501;8856.8671469928;4634.930476042862;4699.506551812079;4723.834665786681;4725.669203155746;4727.503612120747;4325.805338759764;4326.459192600925;4330.372096046056;4330.458692663417;4331.155424692155;3676.17286110623;3682.355587772129;3688.9077773055888;3703.4815125882396;3727.7763334200035;1079.5735684404774;1127.7369346331996;1170.3563040327786;1226.760781647588;1227.5948936850818;1114.749181434187;1123.4158237716342;1163.6101905861462;1309.8690130987015;1418.3759145975737;7148.213696493382;7160.641967901584;7185.568865868065;7212.098487689039;7278.235785242473;1166.3643314393676;1173.6244401791346;1195.390350921019;1201.3092471828734;1209.679434949739;5667.1782591524825;5727.541796656257;5736.139579063174;5745.9425405745405;5746.913796293059;8668.566571671518;8683.435576687496;9005.729828105168;9007.234385077341;9008.864867027;8805.119488051143;8865.290776737846;8897.110288971051;8912.777180570538;8927.642894284285;7118.9644051779405;7139.178994415604;7183.030290910765;7250.6374681265725;7252.057232559839;7367.226441223809;7381.188069312479;7421.962890185515;7433.442491813027;7447.925520744723;8061.1938806118915;8117.228863257981;8232.958835206025;8296.419250652396;8321.708740194064;8293;8349.332053435832;8374.07885132647;8391.66433342684;8399.034111077304;6783.796486105425;6992.860142797013;7050.612216328152;7051.929480705333;7056.788296351117;7501;7530.134034585873;7548.8490230196185;7549.0941087069905;7552.509086909334;5949.583529152917;6742.69657865407;6909.412699920362;6916.654167291716;7113.3953613943395;6820.147481564804;6876.828079298058;6902.965485172542;6943.618101004493;6990.8951365728535;6941.687624057031;7021;7030.226675065636;7031.1211098612675;7032.542884712478;6366;6391.456726178327;6396.42432181106;6415.967920160369;6430.839229019125;6305.527085468517;6371.299157092623;6384.425401713682;6386.584871983307;6400.615963042233 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: |4;prototype.html;343.64261168384866;345.09068604227855;346.62045060658585;352.93470707919033;359.0825440998248;6095.969520152398;6107;6135.417135372144;6144.877102457954;6146.231721034871;5788.247527821388;5857.326407463143;5979.970100149504;6021.698915054251;6055.091736239567;4737.78679959401;4753.1919573672385;4753.382060332152;4758.666893317469;4768.97615511922;4522.8643140705835;4751.809927602892;4762.380890484233;4765.1660959331975;4773.188557945157;4565.657575681837;4619.6997195182375;4724.17326967781;4725.64557658177;4736.59738922192;4685.601723853477;4712.976435117836;4716.716996980193;4721.102990431841;4728.929066064012;4200.369944508348;4205.894852628679;4211.578842115795;4211.810468528924;4212.136512015029;5709.45760152789;5754.482096611283;5785.768569257225;5792.304923409176;5795.449432303966;5540.833774986717;5549.667019978774;5556.833295001156;5559.305086864142;5561.916571251434;6468.6442245676935;6482.124913136713;6482.675866206718;6482.7406903723795;6483.1247781549355;4683.8360657376825;4687.929681054787;4690.108879312954;4691.038337140878;4696.201645720202;6483.351664833526;6657.5006874484125;6664.766733164364;6704.865902681968;6709.5303328767;4673.766311684402;4718.2214934536105;4719.7404142772475;4720.197566413718;4724.740139292303;4773.37946067009;4777.904441911142;4780.95219047805;4784.832530861403;4785.377900872864;5353.411124776349;5468.070428027245;5471.343438787371;5480;5482.780688772405;6518.608883467007;6525.282218955911;6526.28210896801;6528.445082167974;6530.706118224596;1657.651893102438;1664.8168701442833;1666.9166541672869;1667.4914151183764;1669.0736641164028;1129.1982692288402;1134.734770730646;1139.145640769423;1151.8732939376662;1156.4506859241792;6335.176427064452;6337.193899738644;6339.049142628643;6341.524385671093;6345.111684364193;6532.118164047793;6722.529422940445;6737.730490780412;6743.629100399525;6746.831329216809;6288.056791481223;6296.590721603081;6441.484681225491;6442.226932768004;6456.192975878015;3602.2075143468396;3644.1254099016046;3648.2521083177885;3648.908777280589;3649.39784935488;6825.453963682893;6966.825829354205;6981.930180698229;6987.965060174768;6994.370506654425;6917.9308206917285;6918.5502942308585;6932.202796678345;6932.23745388007;6932.79201623942;6962.547434416747;6970.546914450544;6978.092847929736;6981.650917454209;6987.336203060803;6645.401913827779;6765.323467653196;6770.830729231809;6778.627175505395;6780.186377634715;6670.499712521678;6671.8665626686225;6675.33246675348;6678.933210667831;6679.532432729662;6958.234594194736;6965.233824279427;6971.407430368476;6979.790606281819;6981.616011119438;4576.405067341224;4601.861944141681;4695.366125573004;4697.271922851964;4697.318888761042;6680.665966701743;6713.731450741916;6732.091167692299;6733.293004234485;6760.492963027694;6592.868142637216;6593.538452308292;6595.901061484177;6599.175103112111;6601.174853143357;6397.744090236524;6540.822651922699;6579.4736421085245;6580.5064620152725;6594.307597702172;6495.350464953467;6503.902441463477;6507.902381464187;6517.608943463408;6517.869642607026;6637.568558043712;6639.767608133692;6642.97033959737;6648.401643852077;6654.800355989328;6310.148130002391;6461.353864613689;6499.935000649933;6506.934930650633;6508.934910651023 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: |5;yui.html;917.7889085510336;922.6493932305723;929.2380248196478;933.9019402962689;951.2390087929657;14348.210848403334;14494.347754351053;14514.419423223058;14523.78214326786;14525.782113268297;12884.80672789909;12972.675683107927;13060.73878522429;13062.085654004224;13064.738705225913;747.4356860570261;755.9470837041409;757.9621018949058;911.3347256502757;927.0312523413049;888.4491615198586;905.6286922361833;909.3589019741066;909.8134882349102;910.0808183734409;938.0947385772728;953.7615596100975;955.1499165742495;955.8852937647492;956.0153042366373;785.4227143049853;795.677750511044;933.8365785987493;950.2587981374539;951.0632027452954;705.5343473307644;706.9505134640577;710.8969199466101;712.3944647049966;713.0801266366382;13443.19340839543;13566.93216533911;13568.660783480493;13582.049256552045;13625.523106691317;12687.619371418872;13499.66250843727;13514.797278040738;13618;13793.310334483336;10069.647562335356;10220.897791022144;10284.794304113875;10285.845712314322;10402.947985260102;3894.104355998108;3941.940870886939;3948.0722030323136;3953.9011524711823;3967.563568007517;46456.53543464556;46457;46908.7654561725;47440;47508.049839003026;10344.502029843918;10539.156867450585;10637.734056648645;10696.78606427867;10707.089897358655;18006;18168.45494635163;18204.36284730028;18230.908845455953;18287.26850925948;7832.569208693462;7940.722074727357;8230;8241.546714930617;8399.202075802788;4739.3838800955655;4739.668223224341;4767.95232047682;4771.498992605796;4783.067301876101;3132.9216769580944;3151.826649534252;3154.179913222533;3158.6209654841105;3162.0197738701077;556.3518500946352;574.6408494690818;578.1732123064058;582.237269177379;587.0724255676022;648.4520580109894;652.8890088684935;667.4193451696958;676.5129107042922;685.7908337956871;8913.37606367561;9125.543722813833;9208.309376796768;9209.953950230207;9223.216026637676;497.40311626048884;614.9354317796567;685.1025157044288;703.7888633409957;733.1385621894253;4165.354370072643;4287.249731297011;4529.184746745552;4556.863294101182;4569.862904112882;60361.69819150876;61115.69442152761;61451.692741536004;61960.69019654873;61980.38019619836;52320.47679523156;52416.737916310936;52670.20994685083;54352.18471722928;54674;50075;50195.24707129396;50348.49651503511;50573;51217.23174152391;8693.869591956;8752;8754.430961987451;8927;9123.178913897782;42518.5748142527;43110.137797244504;43895.34156987584;44653.330200046374;45661.54338456573;52714;53449.465505346;53479.73260133674;54610;55290;11747.941260293645;12257;12342.81485777696;12384.133110682526;12401.317927514074;13092.607221783363;13213.867861321263;13270.668233293858;13372.86627133755;13555.864441355854;10391.792164156826;10522.579096836042;10742.51658675377;10890.94554527254;11154.16343774204;11827.586034488751;12240.16300387181;12501.374931253218;12541;12651.734826517275;11195.72010699739;11666.533338666359;11847.585334513251;11856.881431185579;12253.081018923438;9764.658236961672;10354.896451035393;10376.481175941324;10425.061744443037;11126.944365278121;10499.895001049892;10775.51510182028;10793.892061079603;10857.457127143769;10862.728431788952 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,813 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: __startTimestamp1455233074715__endTimestamp 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,722 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5564.99 Median:5740.74 stddev:612.17 (10.7%) stddev-sans-first:587.08 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: Values: 3274.3 3378.5 3411.9 3412.5 3431.9 5533.6 5633.3 5635.2 5637.4 5651.0 5619.4 5672.4 5678.1 5678.9 5683.9 4902.5 5115.6 5150.3 5193.3 5238.2 4998.6 5081.8 5084.1 5093.6 5148.5 5135.0 5145.8 5161.7 5163.5 5198.1 4892.0 5086.8 5092.3 5152.6 5152.8 4993.0 5020.3 5089.3 5098.3 5113.7 5345.3 5353.3 5361.3 5374.8 5399.0 5759.9 5874.0 5949.5 5954.9 5965.7 5867.8 5943.3 5962.0 5996.9 5998.3 5628.3 5630.0 5630.5 5631.0 5632.5 5883.5 5972.5 5980.1 6057.6 6122.1 5955.7 5959.8 5960.7 5965.5 5981.2 6037.7 6042.8 6044.0 6045.2 6046.4 5777.4 5879.3 5907.5 5991.7 5993.3 5968.9 5974.8 5997.8 6006.5 6013.0 4581.8 4584.2 4584.3 4587.4 4613.9 4041.3 4079.4 4128.1 4144.6 4160.7 5729.6 5734.0 5734.2 5738.9 5753.0 5965.0 6032.6 6040.2 6040.4 6047.8 5539.4 5631.7 5638.5 5708.7 5757.8 5220.5 5265.8 5271.3 5285.7 5293.0 6042.5 6270.9 6281.5 6301.1 6301.6 5946.3 5998.2 6112.1 6117.1 6120.0 6232.7 6234.5 6235.6 6237.8 6239.5 5760.2 5857.8 5974.8 5978.7 5979.7 5825.9 5910.3 5913.1 5918.9 5941.6 5916.1 5962.3 5963.1 5964.0 5965.1 5627.1 5759.0 5844.9 5908.1 5965.0 5721.2 5722.1 5724.7 5725.2 5755.5 5837.2 5856.1 5857.0 5887.5 5889.5 5449.8 5571.1 5584.2 5703.1 5740.7 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47786.91 Median:33694.33 stddev:77878.14 (231.1%) stddev-sans-first:78016.02 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: Values: 601.6 633.4 644.7 645.3 650.3 33690.5 41604.6 41963.6 41975.4 42024.4 45050.8 45555.3 45570.8 45626.1 45644.0 8194.1 8292.3 8297.3 9205.4 9398.2 11025.2 11263.2 11267.7 11274.8 11286.7 10824.5 10875.3 10894.3 10949.7 10953.1 10828.9 10852.4 10932.4 10936.0 10950.5 10688.1 10848.3 10873.3 10882.2 10885.2 43020.0 43274.4 43361.6 43433.6 43440.6 3337.0 3355.6 3412.1 3486.8 3570.1 33430.0 33698.2 33798.0 33865.0 33899.0 3895.6 4099.7 4110.8 4234.7 4256.2 297211.0 298475.0 298840.0 299630.0 300237.0 56349.0 57580.1 62847.1 63790.4 67629.7 63266.4 63292.0 63598.4 63672.0 63748.0 16545.3 16650.8 16732.2 16756.0 16807.1 13308.9 13351.1 13372.0 13404.3 13430.1 20612.5 21009.3 21044.6 21060.4 21062.4 7643.9 7820.5 7832.3 7939.3 7964.4 1024.7 1096.5 1155.7 1180.6 1210.0 30502.4 30586.7 31067.1 31094.0 31276.8 1089.2 1101.2 1112.7 1163.0 1189.5 13180.0 13274.7 13291.9 13711.8 15934.4 350252.0 351227.0 351540.0 352435.0 352880.0 54674.2 54925.5 54944.5 54946.5 54991.7 1273.9 1347.0 1436.4 1450.1 1472.1 28333.9 29096.9 29573.4 29632.3 30199.5 46122.5 46314.5 46488.0 46494.8 46517.0 231807.0 232047.0 232195.0 232274.0 232346.0 33146.8 33189.7 33352.8 33402.7 33605.2 41209.6 41740.4 41973.0 42284.4 42421.4 36283.3 38165.8 38697.6 38772.4 41354.8 37041.8 37820.0 38385.8 38623.0 38713.6 38272.6 38530.6 38685.0 39010.6 39259.2 30567.5 31938.4 34551.0 34759.1 34910.0 26467.2 26829.2 26955.0 27012.5 27180.2 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31931.41 Median:12519.26 stddev:91988.80 (734.8%) stddev-sans-first:92216.36 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: Values: 385.5 385.6 387.2 387.5 389.4 15234.5 16754.8 17244.3 18622.8 18642.7 16108.4 16298.6 16807.4 18506.5 18540.8 6218.3 6342.6 6356.9 6361.2 6364.7 4710.4 4830.2 6292.6 6983.4 7187.1 6968.5 7079.3 7108.0 7112.8 7167.1 7209.6 7223.1 7236.2 7276.0 7278.2 6803.7 6930.8 6948.8 6954.5 6962.3 22814.7 23290.4 23557.6 23561.9 24215.8 2349.3 2432.4 2540.9 2702.6 2703.5 14801.7 14844.2 14895.9 15045.7 15191.9 2101.9 2132.2 2182.3 2216.8 2223.9 69244.0 71811.6 73566.6 74053.0 74942.0 15608.8 16232.8 16233.8 16239.7 16241.2 24308.0 24308.5 24436.5 24641.8 24673.4 9524.0 9580.9 9590.9 9668.5 9671.1 7803.7 8156.8 8177.2 8180.6 8274.3 7275.2 8120.1 8182.4 8237.7 8324.8 1916.0 1926.5 1945.6 1961.4 2404.0 937.9 942.4 1003.9 1067.2 1072.1 14792.3 15585.6 16697.7 17321.2 18088.2 954.7 1009.3 1026.2 1076.1 1096.7 10214.8 10400.7 10457.8 10516.7 10538.8 554690.0 558406.0 558860.0 569582.0 577787.0 83852.6 85003.0 85101.6 85698.0 88045.6 1167.7 1172.3 1175.8 1188.3 1191.1 10802.2 10863.6 11021.7 16432.4 16633.0 35995.8 36231.0 37467.3 39509.0 39613.0 46955.3 47419.8 47636.8 47711.3 50226.7 15786.6 16083.8 16169.9 16314.3 16418.3 19950.6 20365.3 20371.6 20476.0 20490.6 15042.5 15179.3 15405.8 16749.7 16917.9 15494.3 15704.1 16509.2 16667.7 17045.8 16152.4 16659.2 17296.5 17539.0 17545.2 15793.0 15795.4 15963.8 16035.9 16048.8 16438.3 16523.4 16690.2 16939.2 16979.5 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,724 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5370.75 Median:6385.51 stddev:2711.63 (42.5%) stddev-sans-first:2703.86 15:24:34 INFO - 2016-02-11 15:24:34,726 DEBUG : BROWSER_OUTPUT: Values: 1529.0 1531.8 1707.1 1797.1 1810.9 7336.3 7718.8 7724.3 7726.5 7729.0 6713.2 6877.1 7280.9 7324.1 7541.8 2207.2 2208.7 2235.2 2239.7 2263.6 2028.7 2291.5 2319.9 2330.8 2358.1 2082.3 2288.9 2328.2 2328.5 2329.0 2242.4 2251.3 2257.2 2316.0 2321.0 2262.2 2275.7 2294.5 2294.8 2346.7 7523.4 7600.2 7603.8 7609.7 7615.1 4547.1 4639.0 4642.2 4642.8 4642.9 2917.0 3006.7 3011.2 3022.4 3033.1 966.9 969.6 979.5 998.4 1016.9 10191.5 10202.4 10207.8 10215.0 10228.0 4110.2 4143.8 4163.8 4261.8 4266.4 8845.0 8846.3 8849.2 8853.4 8856.9 4634.9 4699.5 4723.8 4725.7 4727.5 4325.8 4326.5 4330.4 4330.5 4331.2 3676.2 3682.4 3688.9 3703.5 3727.8 1079.6 1127.7 1170.4 1226.8 1227.6 1114.7 1123.4 1163.6 1309.9 1418.4 7148.2 7160.6 7185.6 7212.1 7278.2 1166.4 1173.6 1195.4 1201.3 1209.7 5667.2 5727.5 5736.1 5745.9 5746.9 8668.6 8683.4 9005.7 9007.2 9008.9 8805.1 8865.3 8897.1 8912.8 8927.6 7119.0 7139.2 7183.0 7250.6 7252.1 7367.2 7381.2 7422.0 7433.4 7447.9 8061.2 8117.2 8233.0 8296.4 8321.7 8293.0 8349.3 8374.1 8391.7 8399.0 6783.8 6992.9 7050.6 7051.9 7056.8 7501.0 7530.1 7548.8 7549.1 7552.5 5949.6 6742.7 6909.4 6916.7 7113.4 6820.1 6876.8 6903.0 6943.6 6990.9 6941.7 7021.0 7030.2 7031.1 7032.5 6366.0 6391.5 6396.4 6416.0 6430.8 6305.5 6371.3 6384.4 6386.6 6400.6 15:24:34 INFO - 2016-02-11 15:24:34,726 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,726 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5481.22 Median:6292.32 stddev:1635.42 (26.0%) stddev-sans-first:1593.90 15:24:34 INFO - 2016-02-11 15:24:34,726 DEBUG : BROWSER_OUTPUT: Values: 343.6 345.1 346.6 352.9 359.1 6096.0 6107.0 6135.4 6144.9 6146.2 5788.2 5857.3 5980.0 6021.7 6055.1 4737.8 4753.2 4753.4 4758.7 4769.0 4522.9 4751.8 4762.4 4765.2 4773.2 4565.7 4619.7 4724.2 4725.6 4736.6 4685.6 4713.0 4716.7 4721.1 4728.9 4200.4 4205.9 4211.6 4211.8 4212.1 5709.5 5754.5 5785.8 5792.3 5795.4 5540.8 5549.7 5556.8 5559.3 5561.9 6468.6 6482.1 6482.7 6482.7 6483.1 4683.8 4687.9 4690.1 4691.0 4696.2 6483.4 6657.5 6664.8 6704.9 6709.5 4673.8 4718.2 4719.7 4720.2 4724.7 4773.4 4777.9 4781.0 4784.8 4785.4 5353.4 5468.1 5471.3 5480.0 5482.8 6518.6 6525.3 6526.3 6528.4 6530.7 1657.7 1664.8 1666.9 1667.5 1669.1 1129.2 1134.7 1139.1 1151.9 1156.5 6335.2 6337.2 6339.0 6341.5 6345.1 6532.1 6722.5 6737.7 6743.6 6746.8 6288.1 6296.6 6441.5 6442.2 6456.2 3602.2 3644.1 3648.3 3648.9 3649.4 6825.5 6966.8 6981.9 6988.0 6994.4 6917.9 6918.6 6932.2 6932.2 6932.8 6962.5 6970.5 6978.1 6981.7 6987.3 6645.4 6765.3 6770.8 6778.6 6780.2 6670.5 6671.9 6675.3 6678.9 6679.5 6958.2 6965.2 6971.4 6979.8 6981.6 4576.4 4601.9 4695.4 4697.3 4697.3 6680.7 6713.7 6732.1 6733.3 6760.5 6592.9 6593.5 6595.9 6599.2 6601.2 6397.7 6540.8 6579.5 6580.5 6594.3 6495.4 6503.9 6507.9 6517.6 6517.9 6637.6 6639.8 6643.0 6648.4 6654.8 6310.1 6461.4 6499.9 6506.9 6508.9 15:24:34 INFO - 2016-02-11 15:24:34,727 DEBUG : BROWSER_OUTPUT: 15:24:34 INFO - 2016-02-11 15:24:34,728 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14847.00 Median:46682.88 stddev:17382.06 (37.2%) stddev-sans-first:17399.35 15:24:34 INFO - 2016-02-11 15:24:34,728 DEBUG : BROWSER_OUTPUT: Values: 917.8 922.6 929.2 933.9 951.2 14348.2 14494.3 14514.4 14523.8 14525.8 12884.8 12972.7 13060.7 13062.1 13064.7 747.4 755.9 758.0 911.3 927.0 888.4 905.6 909.4 909.8 910.1 938.1 953.8 955.1 955.9 956.0 785.4 795.7 933.8 950.3 951.1 705.5 707.0 710.9 712.4 713.1 13443.2 13566.9 13568.7 13582.0 13625.5 12687.6 13499.7 13514.8 13618.0 13793.3 10069.6 10220.9 10284.8 10285.8 10402.9 3894.1 3941.9 3948.1 3953.9 3967.6 46456.5 46457.0 46908.8 47440.0 47508.0 10344.5 10539.2 10637.7 10696.8 10707.1 18006.0 18168.5 18204.4 18230.9 18287.3 7832.6 7940.7 8230.0 8241.5 8399.2 4739.4 4739.7 4768.0 4771.5 4783.1 3132.9 3151.8 3154.2 3158.6 3162.0 556.4 574.6 578.2 582.2 587.1 648.5 652.9 667.4 676.5 685.8 8913.4 9125.5 9208.3 9210.0 9223.2 497.4 614.9 685.1 703.8 733.1 4165.4 4287.2 4529.2 4556.9 4569.9 60361.7 61115.7 61451.7 61960.7 61980.4 52320.5 52416.7 52670.2 54352.2 54674.0 50075.0 50195.2 50348.5 50573.0 51217.2 8693.9 8752.0 8754.4 8927.0 9123.2 42518.6 43110.1 43895.3 44653.3 45661.5 52714.0 53449.5 53479.7 54610.0 55290.0 11747.9 12257.0 12342.8 12384.1 12401.3 13092.6 13213.9 13270.7 13372.9 13555.9 10391.8 10522.6 10742.5 10890.9 11154.2 11827.6 12240.2 12501.4 12541.0 12651.7 11195.7 11666.5 11847.6 11856.9 12253.1 9764.7 10354.9 10376.5 10425.1 11126.9 10499.9 10775.5 10793.9 10857.5 10862.7 15:24:34 INFO - 2016-02-11 15:24:34,728 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:24:34 INFO - 2016-02-11 15:24:34,729 DEBUG : BROWSER_OUTPUT: 15:24:35 INFO - 2016-02-11 15:24:35,326 INFO : Browser exited with error code: 0 15:24:35 INFO - 2016-02-11 15:24:35,347 INFO : Completed test dromaeo_css (00:18:29) 15:24:35 INFO - 2016-02-11 15:24:35,347 INFO : Starting test kraken 15:24:35 INFO - 2016-02-11 15:24:35,347 DEBUG : operating with platform_type : linux_ 15:24:35 INFO - 2016-02-11 15:24:35,347 INFO : Initialising browser for kraken test... 15:24:35 INFO - 2016-02-11 15:24:35,353 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVJ9BDG/profile http://localhost:37404/getInfo.html 15:24:39 INFO - 2016-02-11 15:24:39,068 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:24:39 INFO - 2016-02-11 15:24:39,068 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:24:39 INFO - 2016-02-11 15:24:39,068 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:24:39 INFO - 2016-02-11 15:24:39,068 DEBUG : BROWSER_OUTPUT: __metrics 15:24:39 INFO - 2016-02-11 15:24:39,205 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] 15:24:39 INFO - 2016-02-11 15:24:39,950 INFO : Browser initialized. 15:24:39 INFO - 2016-02-11 15:24:39,950 INFO : Running cycle 1/1 for kraken test... 15:24:39 INFO - 2016-02-11 15:24:39,950 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVJ9BDG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:24:51 INFO - 2016-02-11 15:24:51,620 DEBUG : BROWSER_OUTPUT: RSS: Main: 133804032 15:24:51 INFO - 2016-02-11 15:24:51,620 DEBUG : BROWSER_OUTPUT: 15:24:54 INFO - 2016-02-11 15:24:54,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37404/tests/kraken/driver.html?testName=audio-beat-detection) 15:24:54 INFO - 2016-02-11 15:24:54,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 165007360 15:24:54 INFO - 2016-02-11 15:24:54,652 DEBUG : BROWSER_OUTPUT: 15:24:57 INFO - 2016-02-11 15:24:57,238 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37404/tests/kraken/driver.html?testName=audio-dft) 15:24:57 INFO - 2016-02-11 15:24:57,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 161959936 15:24:57 INFO - 2016-02-11 15:24:57,812 DEBUG : BROWSER_OUTPUT: 15:25:00 INFO - 2016-02-11 15:25:00,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37404/tests/kraken/driver.html?testName=audio-fft) 15:25:01 INFO - 2016-02-11 15:25:01,224 DEBUG : BROWSER_OUTPUT: RSS: Main: 161206272 15:25:01 INFO - 2016-02-11 15:25:01,224 DEBUG : BROWSER_OUTPUT: 15:25:03 INFO - 2016-02-11 15:25:03,215 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37404/tests/kraken/driver.html?testName=audio-oscillator) 15:25:03 INFO - 2016-02-11 15:25:03,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 159481856 15:25:03 INFO - 2016-02-11 15:25:03,777 DEBUG : BROWSER_OUTPUT: 15:25:05 INFO - 2016-02-11 15:25:05,962 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37404/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:25:06 INFO - 2016-02-11 15:25:06,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 157241344 15:25:06 INFO - 2016-02-11 15:25:06,523 DEBUG : BROWSER_OUTPUT: 15:25:09 INFO - 2016-02-11 15:25:09,367 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37404/tests/kraken/driver.html?testName=imaging-darkroom) 15:25:09 INFO - 2016-02-11 15:25:09,949 DEBUG : BROWSER_OUTPUT: RSS: Main: 158437376 15:25:09 INFO - 2016-02-11 15:25:09,949 DEBUG : BROWSER_OUTPUT: 15:25:13 INFO - 2016-02-11 15:25:13,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37404/tests/kraken/driver.html?testName=imaging-desaturate) 15:25:13 INFO - 2016-02-11 15:25:13,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 160673792 15:25:13 INFO - 2016-02-11 15:25:13,847 DEBUG : BROWSER_OUTPUT: 15:25:16 INFO - 2016-02-11 15:25:16,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37404/tests/kraken/driver.html?testName=json-parse-financial) 15:25:17 INFO - 2016-02-11 15:25:17,159 DEBUG : BROWSER_OUTPUT: RSS: Main: 160198656 15:25:17 INFO - 2016-02-11 15:25:17,159 DEBUG : BROWSER_OUTPUT: 15:25:18 INFO - 2016-02-11 15:25:18,707 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37404/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:25:19 INFO - 2016-02-11 15:25:19,268 DEBUG : BROWSER_OUTPUT: RSS: Main: 166780928 15:25:19 INFO - 2016-02-11 15:25:19,268 DEBUG : BROWSER_OUTPUT: 15:25:21 INFO - 2016-02-11 15:25:21,045 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:25:21 INFO - 2016-02-11 15:25:21,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 166096896 15:25:21 INFO - 2016-02-11 15:25:21,636 DEBUG : BROWSER_OUTPUT: 15:25:23 INFO - 2016-02-11 15:25:23,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:25:24 INFO - 2016-02-11 15:25:24,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 171466752 15:25:24 INFO - 2016-02-11 15:25:24,230 DEBUG : BROWSER_OUTPUT: 15:25:26 INFO - 2016-02-11 15:25:26,564 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:25:27 INFO - 2016-02-11 15:25:27,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 177831936 15:25:27 INFO - 2016-02-11 15:25:27,141 DEBUG : BROWSER_OUTPUT: 15:25:29 INFO - 2016-02-11 15:25:29,607 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:25:30 INFO - 2016-02-11 15:25:30,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 174817280 15:25:30 INFO - 2016-02-11 15:25:30,181 DEBUG : BROWSER_OUTPUT: 15:25:31 INFO - 2016-02-11 15:25:31,816 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37404/tests/kraken/driver.html?testName=ai-astar) 15:25:32 INFO - 2016-02-11 15:25:32,066 DEBUG : BROWSER_OUTPUT: RSS: Main: 233742336 15:25:32 INFO - 2016-02-11 15:25:32,066 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,066 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;103;108;109;108;98;109;109;110 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;159;129;155;160;121;148;149;122;161;121 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;161;159;158;161;160;161;161;160 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;73;181;77;73;72;78;146;72 15:25:32 INFO - 2016-02-11 15:25:32,067 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;145;91;92;93;141;92;92;137 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;109;108;109;109;109;110 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;147;145;146;145;146;146;145 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;97;97;96;96;96 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;57;56;58;56;57;56;59;54 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;65;64;64;67;65;64;65;65 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;78;81;81;80;89;79;79;79;81 15:25:32 INFO - 2016-02-11 15:25:32,068 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;120;114;112;123;113;112;113;111;116 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;143;143;144;142;141;143;141;141;131 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;64;63;65;63;65;63;64;63 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,929 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:25:32 INFO - 2016-02-11 15:25:32,069 DEBUG : BROWSER_OUTPUT: __startTimestamp1455233132065__endTimestamp 15:25:32 INFO - 2016-02-11 15:25:32,070 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,073 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:25:32 INFO - 2016-02-11 15:25:32,073 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:25:32 INFO - 2016-02-11 15:25:32,073 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,073 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.00 Median:109.00 stddev:3.71 (3.4%) stddev-sans-first:3.94 15:25:32 INFO - 2016-02-11 15:25:32,074 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 103.0 108.0 109.0 108.0 98.0 109.0 109.0 110.0 15:25:32 INFO - 2016-02-11 15:25:32,074 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,074 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.50 Median:152.00 stddev:17.24 (11.3%) stddev-sans-first:17.23 15:25:32 INFO - 2016-02-11 15:25:32,074 DEBUG : BROWSER_OUTPUT: Values: 159.0 129.0 155.0 160.0 121.0 148.0 149.0 122.0 161.0 121.0 15:25:32 INFO - 2016-02-11 15:25:32,074 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 161.0 159.0 158.0 161.0 160.0 161.0 161.0 160.0 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:91.60 Median:72.50 stddev:38.84 (53.6%) stddev-sans-first:40.55 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 73.0 181.0 77.0 73.0 72.0 78.0 146.0 72.0 15:25:32 INFO - 2016-02-11 15:25:32,075 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:92.00 stddev:23.55 (25.6%) stddev-sans-first:24.43 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 145.0 91.0 92.0 93.0 141.0 92.0 92.0 137.0 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 109.0 108.0 109.0 109.0 109.0 110.0 15:25:32 INFO - 2016-02-11 15:25:32,076 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 147.0 145.0 146.0 145.0 146.0 146.0 145.0 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 97.0 97.0 96.0 96.0 96.0 15:25:32 INFO - 2016-02-11 15:25:32,077 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,078 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.30 Median:56.50 stddev:1.49 (2.6%) stddev-sans-first:1.51 15:25:32 INFO - 2016-02-11 15:25:32,078 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 57.0 56.0 58.0 56.0 57.0 56.0 59.0 54.0 15:25:32 INFO - 2016-02-11 15:25:32,078 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,078 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:1.07 (1.7%) stddev-sans-first:0.97 15:25:32 INFO - 2016-02-11 15:25:32,078 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 65.0 64.0 64.0 67.0 65.0 64.0 65.0 65.0 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.50 stddev:3.13 (3.9%) stddev-sans-first:3.27 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: Values: 79.0 78.0 81.0 81.0 80.0 89.0 79.0 79.0 79.0 81.0 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.00 Median:115.00 stddev:3.86 (3.4%) stddev-sans-first:4.08 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: Values: 116.0 120.0 114.0 112.0 123.0 113.0 112.0 113.0 111.0 116.0 15:25:32 INFO - 2016-02-11 15:25:32,079 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.70 Median:142.50 stddev:3.80 (2.7%) stddev-sans-first:3.91 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: Values: 138.0 143.0 143.0 144.0 142.0 141.0 143.0 141.0 141.0 131.0 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:0.88 (1.4%) stddev-sans-first:0.93 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 63.0 65.0 63.0 65.0 63.0 64.0 63.0 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:25:32 INFO - 2016-02-11 15:25:32,080 DEBUG : BROWSER_OUTPUT: 15:25:32 INFO - 2016-02-11 15:25:32,742 INFO : Browser exited with error code: 0 15:25:32 INFO - 2016-02-11 15:25:32,798 INFO : Completed test kraken (00:00:57) 15:25:32 INFO - 2016-02-11 15:25:32,798 INFO : Starting test v8_7 15:25:32 INFO - 2016-02-11 15:25:32,798 DEBUG : operating with platform_type : linux_ 15:25:32 INFO - 2016-02-11 15:25:32,799 INFO : Initialising browser for v8_7 test... 15:25:32 INFO - 2016-02-11 15:25:32,805 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDTKS6Q/profile http://localhost:37404/getInfo.html 15:25:36 INFO - 2016-02-11 15:25:36,522 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:25:36 INFO - 2016-02-11 15:25:36,522 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:25:36 INFO - 2016-02-11 15:25:36,522 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:25:36 INFO - 2016-02-11 15:25:36,522 DEBUG : BROWSER_OUTPUT: __metrics 15:25:36 INFO - 2016-02-11 15:25:36,671 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] 15:25:37 INFO - 2016-02-11 15:25:37,454 INFO : Browser initialized. 15:25:37 INFO - 2016-02-11 15:25:37,454 INFO : Running cycle 1/1 for v8_7 test... 15:25:37 INFO - 2016-02-11 15:25:37,455 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDTKS6Q/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:25:49 INFO - 2016-02-11 15:25:49,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 132759552 15:25:49 INFO - 2016-02-11 15:25:49,114 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,481 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37404/tests/v8_7/run.html (next: http://localhost:37404/tests/v8_7/run.html) 15:26:10 INFO - 2016-02-11 15:26:10,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 428187648 15:26:10 INFO - 2016-02-11 15:26:10,731 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,732 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:26:10 INFO - 2016-02-11 15:26:10,733 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:26:10 INFO - 2016-02-11 15:26:10,733 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:26:10 INFO - 2016-02-11 15:26:10,733 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:26:10 INFO - 2016-02-11 15:26:10,733 DEBUG : BROWSER_OUTPUT: |0;Richards;168.49199663016006 15:26:10 INFO - 2016-02-11 15:26:10,734 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.74464579901152 15:26:10 INFO - 2016-02-11 15:26:10,734 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 15:26:10 INFO - 2016-02-11 15:26:10,734 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5154.639175257732 15:26:10 INFO - 2016-02-11 15:26:10,735 DEBUG : BROWSER_OUTPUT: |4;RayTrace;925.0693802035153 15:26:10 INFO - 2016-02-11 15:26:10,735 DEBUG : BROWSER_OUTPUT: |5;Earley;542.0054200542005 15:26:10 INFO - 2016-02-11 15:26:10,735 DEBUG : BROWSER_OUTPUT: |6;Boyer;10557.894736842105 15:26:10 INFO - 2016-02-11 15:26:10,735 DEBUG : BROWSER_OUTPUT: |7;RegExp;25100 15:26:10 INFO - 2016-02-11 15:26:10,736 DEBUG : BROWSER_OUTPUT: |8;Splay;499.7501249375312 15:26:10 INFO - 2016-02-11 15:26:10,736 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 15:26:10 INFO - 2016-02-11 15:26:10,736 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:26:10 INFO - 2016-02-11 15:26:10,737 DEBUG : BROWSER_OUTPUT: __startTimestamp1455233170731__endTimestamp 15:26:10 INFO - 2016-02-11 15:26:10,737 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,737 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:26:10 INFO - 2016-02-11 15:26:10,737 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:26:10 INFO - 2016-02-11 15:26:10,737 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,738 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.49 Median:168.49 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,738 DEBUG : BROWSER_OUTPUT: Values: 168.5 15:26:10 INFO - 2016-02-11 15:26:10,738 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,739 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,739 DEBUG : BROWSER_OUTPUT: Values: 164.7 15:26:10 INFO - 2016-02-11 15:26:10,739 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,739 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,740 DEBUG : BROWSER_OUTPUT: Values: 279.1 15:26:10 INFO - 2016-02-11 15:26:10,740 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,740 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5154.64 Median:5154.64 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,741 DEBUG : BROWSER_OUTPUT: Values: 5154.6 15:26:10 INFO - 2016-02-11 15:26:10,741 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,741 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:925.07 Median:925.07 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,741 DEBUG : BROWSER_OUTPUT: Values: 925.1 15:26:10 INFO - 2016-02-11 15:26:10,741 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.01 Median:542.01 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: Values: 542.0 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10557.89 Median:10557.89 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: Values: 10557.9 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25100.00 Median:25100.00 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,742 DEBUG : BROWSER_OUTPUT: Values: 25100.0 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:499.75 Median:499.75 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: Values: 499.8 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: Values: 4826.9 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:26:10 INFO - 2016-02-11 15:26:10,743 DEBUG : BROWSER_OUTPUT: 15:26:11 INFO - 2016-02-11 15:26:11,442 INFO : Browser exited with error code: 0 15:26:11 INFO - 2016-02-11 15:26:11,454 INFO : Completed test v8_7 (00:00:38) 15:26:11 INFO - 2016-02-11 15:26:11,772 INFO : Completed test suite (00:20:05) 15:26:11 INFO - 2016-02-11 15:26:11,773 DEBUG : Working with test: dromaeo_css 15:26:11 INFO - 2016-02-11 15:26:11,773 DEBUG : Generating results file: dromaeo_css 15:26:11 INFO - 2016-02-11 15:26:11,774 DEBUG : Working with test: kraken 15:26:11 INFO - 2016-02-11 15:26:11,774 DEBUG : Generating results file: kraken 15:26:11 INFO - 2016-02-11 15:26:11,774 INFO : javascript benchmark 15:26:11 INFO - 2016-02-11 15:26:11,774 DEBUG : Working with test: v8_7 15:26:11 INFO - 2016-02-11 15:26:11,775 DEBUG : Generating results file: v8_7 15:26:11 INFO - 2016-02-11 15:26:11,775 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:26:11 INFO - 2016-02-11 15:26:11,829 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:26:11 INFO - 2016-02-11 15:26:11,829 DEBUG : process_Request line: dromaeo_css 7462.96 graph.html#tests=[[72,53,35]] 15:26:11 INFO - 2016-02-11 15:26:11,829 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:26:11 INFO - 2016-02-11 15:26:11,859 DEBUG : process_Request line: kraken 1479.80 graph.html#tests=[[232,53,35]] 15:26:11 INFO - 2016-02-11 15:26:11,859 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:26:11 INFO - 2016-02-11 15:26:11,887 DEBUG : process_Request line: v8_7 23372.73 graph.html#tests=[[230,53,35]] 15:26:11 INFO - 2016-02-11 15:26:11,888 INFO : javascript benchmark 15:26:11 INFO - 2016-02-11 15:26:11,892 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3274.2632907595785, 3378.493226016099, 3411.9317613647727, 3412.5051867479215, 3431.8798842040524, 5533.557315414767, 5633.324001119866, 5635.239242702234, 5637.379888212294, 5651.039323315036, 5619.438056194378, 5672.432756724325, 5678.148277758339, 5678.886422271552, 5683.886322273552, 4902.509749025086, 5115.590752739781, 5150.304708864312, 5193.298904647881, 5238.214267859832, 4998.5501304882555, 5081.771320290596, 5084.13569693153, 5093.592512598983, 5148.459411761767, 5135.0, 5145.768440420171, 5161.741912904358, 5163.509466600667, 5198.09033419152, 4891.951080489185, 5086.79652813887, 5092.337996060525, 5152.587792976553, 5152.793888244484, 4992.950070499285, 5020.297158397827, 5089.287499750038, 5098.260752190911, 5113.693178409308, 5345.33183352081, 5353.304070470853, 5361.276227709248, 5374.7850085996515, 5399.0, 5759.856003599902, 5874.0, 5949.46454819064, 5954.910676339859, 5965.731542080617, 5867.76528938842, 5943.3165186003735, 5961.970190149022, 5996.880062398771, 5998.310194327664, 5628.324601047819, 5630.042892708249, 5630.465105814982, 5631.042722737145, 5632.464915832989, 5883.470487656132, 5972.462478376967, 5980.132880732356, 6057.575969682168, 6122.081687746873, 5955.702214889325, 5959.761609535657, 5960.701964901824, 5965.492933100735, 5981.222441082718, 6037.72830222641, 6042.758289668452, 6043.969780151071, 6045.153678484928, 6046.365131661112, 5777.422257774189, 5879.265091863517, 5907.4683278505145, 5991.670458124845, 5993.310769261503, 5968.880622387528, 5974.820755377345, 5997.790077347272, 6006.519478441802, 6013.0, 4581.79381927814, 4584.17484852723, 4584.33527138563, 4587.38070360497, 4613.930791038137, 4041.312976793893, 4079.3676980068135, 4128.1330920507025, 4144.606262405066, 4160.729552579133, 5729.570282228766, 5734.0, 5734.197212390269, 5738.913916291259, 5753.021986262345, 5964.970175149097, 6032.577719559677, 6040.244969378828, 6040.426159514839, 6047.758089676452, 5539.446055394428, 5631.690257035821, 5638.464345887134, 5708.71456427177, 5757.827265182052, 5220.504052115043, 5265.842024739264, 5271.288376069258, 5285.68285902847, 5293.0472514946905, 6042.546808989343, 6270.9372906271265, 6281.466075383552, 6301.086342480315, 6301.6219026858535, 5946.316173640087, 5998.220231369894, 6112.147481619707, 6117.062412996569, 6119.966902931789, 6232.657203853832, 6234.501239900887, 6235.594686345373, 6237.844053898507, 6239.469645080219, 5760.193572899713, 5857.7949771757785, 5974.790882319098, 5978.730957106854, 5979.671118088373, 5825.912611310749, 5910.261217347832, 5913.05391137416, 5918.881622367615, 5941.613795103304, 5916.053431450947, 5962.314333851489, 5963.105534169736, 5963.970180149245, 5965.075413310944, 5627.099664053731, 5759.04975679025, 5844.94155058444, 5908.084246941731, 5964.970175149097, 5721.1990321354215, 5722.084466485342, 5724.713764311851, 5725.19847221381, 5755.539556835527, 5837.153612726216, 5856.12158176277, 5857.0, 5887.499562537232, 5889.499392551681, 5449.836504904701, 5571.13647384656, 5584.246126772834, 5703.116017017369, 5740.741666625096], "unit": "ms", "name": "dojo.html", "value": 5525.785767210415}, {"lowerIsBetter": false, "replicates": [601.5819005790975, 633.3792882974685, 644.6744394080988, 645.2643985856123, 650.2814390098938, 33690.49464258038, 41604.58395416045, 41963.58036419642, 41975.370369444485, 42024.36963445551, 45050.774746126226, 45555.31667024997, 45570.77214613922, 45626.08747825042, 45644.0, 8194.09864914859, 8292.253697167254, 8297.253247207751, 9205.447673139599, 9398.154166125047, 11025.173112016591, 11263.155263355282, 11267.718307042349, 11274.774504509905, 11286.661400157967, 10824.458777061154, 10875.347479151236, 10894.291871028357, 10949.671509854717, 10953.123750100012, 10828.945855270713, 10852.403117828517, 10932.398718070503, 10936.0, 10950.452477376139, 10688.14494840411, 10848.349099054003, 10873.347599144077, 10882.23824332297, 10885.23803333767, 43020.0, 43274.350884736756, 43361.56638433607, 43433.56566434327, 43440.56559434397, 3336.9833150834093, 3355.580552430946, 3412.146963259185, 3486.7733597316346, 3570.107473131717, 33430.0, 33698.1575460613, 33798.0, 33865.0, 33899.0, 3895.61043895611, 4099.713020088595, 4110.753354798691, 4234.6823988201, 4256.191323648497, 297211.0, 298475.0, 298840.0, 299630.0, 300237.0, 56349.0, 57580.13629795556, 62847.05729414063, 63790.36209637937, 67629.66185169043, 63266.36733632697, 63292.0, 63598.36401636017, 63672.0, 63748.0, 16545.25546350417, 16650.75023874643, 16732.24704888283, 16756.0, 16807.075610841275, 13308.866911330762, 13351.065425420322, 13372.0, 13404.262765547794, 13430.0598958072, 20612.484687882923, 21009.26467573658, 21044.579108417747, 21060.36818895405, 21062.368128956157, 7643.885341719767, 7820.452568320163, 7832.334251588679, 7939.325157361689, 7964.362850971908, 1024.7386916336286, 1096.5120521367915, 1155.6767501211066, 1180.5690922813112, 1210.0138387214392, 30502.389952201276, 30586.694133058383, 31067.067987959945, 31094.0, 31276.84361578223, 1089.1831126655009, 1101.1576144249655, 1112.7273817914502, 1163.0230606290756, 1189.542026319873, 13180.0, 13274.668133296746, 13291.867081329063, 13711.794323085162, 15934.362625495081, 350252.0, 351227.0, 351540.0, 352435.0, 352880.0, 54674.179887301725, 54925.45074549283, 54944.45055549393, 54946.450535494136, 54991.725041374535, 1273.9108262421541, 1347.0435990446697, 1436.3751767980962, 1450.0864455393034, 1472.138798802691, 28333.858330708215, 29096.854515727286, 29573.408531829675, 29632.259193520335, 30199.547006795354, 46122.53877461317, 46314.53685463102, 46488.0, 46494.767526162155, 46517.0, 231807.0, 232047.0, 232195.0, 232274.0, 232346.0, 33146.83426582852, 33189.668103318654, 33352.83323583366, 33402.66597333996, 33605.15987100244, 41209.58790412058, 41740.373894392214, 41973.0, 42284.36573451462, 42421.363679544214, 36283.27433451264, 38165.80917095508, 38697.6130238694, 38772.41841372438, 41354.793226033675, 37041.814790926954, 37820.0, 38385.808070959465, 38623.03442413962, 38713.61286387213, 38272.61727382691, 38530.6146938527, 38685.0, 39010.6098939007, 39259.214815704094, 30567.54148687816, 31938.36123277568, 34551.0, 34759.13102172466, 34910.0, 26467.205983820517, 26829.195124146307, 26955.0, 27012.45975080527, 27180.184594462196], "unit": "ms", "name": "ext.html", "value": 19748.025870725687}, {"lowerIsBetter": false, "replicates": [385.54698229580237, 385.59705108161967, 387.17338482340193, 387.48271058137396, 389.3653345047572, 15234.54296371108, 16754.83245167548, 17244.310227590897, 18622.81377186228, 18642.720359194624, 16108.436204732836, 16298.592535186626, 16807.411740589083, 18506.53733656656, 18540.814591854112, 6218.284897236819, 6342.587731797442, 6356.936430635681, 6361.236651601815, 6364.7135878885565, 4710.364100846393, 4830.227163653816, 6292.622442653433, 6983.441324694038, 7187.137543494788, 6968.512204145712, 7079.327463890922, 7108.0, 7112.751053713121, 7167.139943206823, 7209.639518024078, 7223.133224013126, 7236.167840698335, 7275.9636201819185, 7278.235785242527, 6803.727850885959, 6930.826729331807, 6948.826279343057, 6954.547954382949, 6962.338577835098, 22814.65778013331, 23290.417739556477, 23557.646635300483, 23561.882190588938, 24215.757842421703, 2349.3186975777003, 2432.4405386761146, 2540.8602526861014, 2702.6216329713857, 2703.4863375958503, 14801.703965920622, 14844.183569903651, 14895.851041489555, 15045.699086018329, 15191.924040379838, 2101.9159233630635, 2132.1897678881924, 2182.2798476502876, 2216.767239439852, 2223.8665680059084, 69244.0, 71811.64094179496, 73566.63216683936, 74053.0, 74942.0, 15608.765868511982, 16232.837671623369, 16233.756493652605, 16239.675206495804, 16241.187940602922, 24308.0, 24308.513829723306, 24436.511269774506, 24641.753582464306, 24673.383165421008, 9523.952380238054, 9580.856287155699, 9590.856137157949, 9668.516574171263, 9671.081247281498, 7803.7268695595385, 8156.83686326282, 8177.170366833766, 8180.550069746102, 8274.338052955749, 7275.163356213997, 8120.147384524658, 8182.388496659047, 8237.670493180325, 8324.833503329899, 1915.9712604310946, 1926.489480287725, 1945.6206039822098, 1961.4311849563753, 2403.9542798882544, 937.8686983822271, 942.3921570586933, 1003.9498025098716, 1067.199600299775, 1072.0940805019902, 14792.334344954503, 15585.610359740871, 16697.749533757004, 17321.220545075248, 18088.186031628607, 954.7183580843667, 1009.2783659683362, 1026.2303272545591, 1076.05844885725, 1096.6984079378076, 10214.846777298346, 10400.739981500523, 10457.790844183073, 10516.68449946503, 10538.789224215472, 554690.0, 558406.0, 558860.0, 569582.0, 577787.0, 83852.58073709592, 85003.0, 85101.57449212838, 85698.0, 88045.55977220072, 1167.7139100920328, 1172.279048385237, 1175.7648470305974, 1188.2751521571834, 1191.0828661930439, 10802.243842931233, 10863.565457381615, 11021.669349919515, 16432.424865129662, 16633.0, 35995.82002089973, 36231.0, 37467.2506549862, 39509.0, 39613.0, 46955.29567056429, 47419.76290118528, 47636.761816190694, 47711.28433073576, 50226.74886625543, 15786.605334866721, 16083.75874361862, 16169.919150404172, 16314.265858036655, 16418.343266269694, 19950.600987980448, 20365.287214947406, 20371.59256814885, 20476.0, 20490.590188195856, 15042.473513426978, 15179.316930738365, 15405.76891346653, 16749.66500670004, 16917.91541042287, 15494.302756375764, 15704.057756534645, 16509.17454127313, 16667.66664666724, 17045.829541704425, 16152.353905843634, 16659.16704164811, 17296.481105566854, 17539.0, 17545.210465528828, 15793.0, 15795.447159349367, 15963.840361596236, 16035.919820400823, 16048.759268611213, 16438.260278287267, 16523.421680241136, 16690.24893879754, 16939.23773430223, 16979.49061528156], "unit": "ms", "name": "jquery.html", "value": 10669.958220556153}, {"lowerIsBetter": false, "replicates": [1529.0443472829481, 1531.8314985351617, 1707.1037705204758, 1797.1194114883706, 1810.8501101800362, 7336.3397294243505, 7718.768436946893, 7724.266194711493, 7726.459147859652, 7729.0, 6713.227978782442, 6877.140357455318, 7280.890786638205, 7324.08448943882, 7541.811454713635, 2207.2274703853645, 2208.712867327253, 2235.1729859951843, 2239.675247089171, 2263.592553340398, 2028.6652702304102, 2291.5073259249257, 2319.8724070176136, 2330.8135349172026, 2358.1157066100127, 2082.312836763872, 2288.9313320600327, 2328.2316835444262, 2328.499372634883, 2329.0218108394515, 2242.439390152462, 2251.313349428424, 2257.209976508217, 2315.988420057906, 2320.9651855222187, 2262.162999690125, 2275.7041584594062, 2294.5066810635626, 2294.8278879084137, 2346.6597343385115, 7523.360514356286, 7600.201978792203, 7603.847923041508, 7609.695612175562, 7615.124260710034, 4547.136044151601, 4639.025804581042, 4642.234031384851, 4642.814287428532, 4642.86071417858, 2917.0, 3006.699330066998, 3011.2471882029495, 3022.440848443045, 3033.074912151793, 966.9226461883103, 969.60730903984, 979.4759803505161, 998.4308943901979, 1016.9237307201989, 10191.541380637815, 10202.438865862374, 10207.795844083152, 10215.02957219063, 10228.0, 4110.219058378899, 4143.7720925349395, 4163.812628431728, 4261.765602891809, 4266.4240327556, 8845.027047024822, 8846.292296616255, 8849.20357167858, 8853.380263381501, 8856.8671469928, 4634.930476042862, 4699.506551812079, 4723.834665786681, 4725.669203155746, 4727.503612120747, 4325.805338759764, 4326.459192600925, 4330.372096046056, 4330.458692663417, 4331.155424692155, 3676.17286110623, 3682.355587772129, 3688.9077773055888, 3703.4815125882396, 3727.7763334200035, 1079.5735684404774, 1127.7369346331996, 1170.3563040327786, 1226.760781647588, 1227.5948936850818, 1114.749181434187, 1123.4158237716342, 1163.6101905861462, 1309.8690130987015, 1418.3759145975737, 7148.213696493382, 7160.641967901584, 7185.568865868065, 7212.098487689039, 7278.235785242473, 1166.3643314393676, 1173.6244401791346, 1195.390350921019, 1201.3092471828734, 1209.679434949739, 5667.1782591524825, 5727.541796656257, 5736.139579063174, 5745.9425405745405, 5746.913796293059, 8668.566571671518, 8683.435576687496, 9005.729828105168, 9007.234385077341, 9008.864867027, 8805.119488051143, 8865.290776737846, 8897.110288971051, 8912.777180570538, 8927.642894284285, 7118.9644051779405, 7139.178994415604, 7183.030290910765, 7250.6374681265725, 7252.057232559839, 7367.226441223809, 7381.188069312479, 7421.962890185515, 7433.442491813027, 7447.925520744723, 8061.1938806118915, 8117.228863257981, 8232.958835206025, 8296.419250652396, 8321.708740194064, 8293.0, 8349.332053435832, 8374.07885132647, 8391.66433342684, 8399.034111077304, 6783.796486105425, 6992.860142797013, 7050.612216328152, 7051.929480705333, 7056.788296351117, 7501.0, 7530.134034585873, 7548.8490230196185, 7549.0941087069905, 7552.509086909334, 5949.583529152917, 6742.69657865407, 6909.412699920362, 6916.654167291716, 7113.3953613943395, 6820.147481564804, 6876.828079298058, 6902.965485172542, 6943.618101004493, 6990.8951365728535, 6941.687624057031, 7021.0, 7030.226675065636, 7031.1211098612675, 7032.542884712478, 6366.0, 6391.456726178327, 6396.42432181106, 6415.967920160369, 6430.839229019125, 6305.527085468517, 6371.299157092623, 6384.425401713682, 6386.584871983307, 6400.615963042233], "unit": "ms", "name": "mootools.html", "value": 4455.746261876356}, {"lowerIsBetter": false, "replicates": [343.64261168384866, 345.09068604227855, 346.62045060658585, 352.93470707919033, 359.0825440998248, 6095.969520152398, 6107.0, 6135.417135372144, 6144.877102457954, 6146.231721034871, 5788.247527821388, 5857.326407463143, 5979.970100149504, 6021.698915054251, 6055.091736239567, 4737.78679959401, 4753.1919573672385, 4753.382060332152, 4758.666893317469, 4768.97615511922, 4522.8643140705835, 4751.809927602892, 4762.380890484233, 4765.1660959331975, 4773.188557945157, 4565.657575681837, 4619.6997195182375, 4724.17326967781, 4725.64557658177, 4736.59738922192, 4685.601723853477, 4712.976435117836, 4716.716996980193, 4721.102990431841, 4728.929066064012, 4200.369944508348, 4205.894852628679, 4211.578842115795, 4211.810468528924, 4212.136512015029, 5709.45760152789, 5754.482096611283, 5785.768569257225, 5792.304923409176, 5795.449432303966, 5540.833774986717, 5549.667019978774, 5556.833295001156, 5559.305086864142, 5561.916571251434, 6468.6442245676935, 6482.124913136713, 6482.675866206718, 6482.7406903723795, 6483.1247781549355, 4683.8360657376825, 4687.929681054787, 4690.108879312954, 4691.038337140878, 4696.201645720202, 6483.351664833526, 6657.5006874484125, 6664.766733164364, 6704.865902681968, 6709.5303328767, 4673.766311684402, 4718.2214934536105, 4719.7404142772475, 4720.197566413718, 4724.740139292303, 4773.37946067009, 4777.904441911142, 4780.95219047805, 4784.832530861403, 4785.377900872864, 5353.411124776349, 5468.070428027245, 5471.343438787371, 5480.0, 5482.780688772405, 6518.608883467007, 6525.282218955911, 6526.28210896801, 6528.445082167974, 6530.706118224596, 1657.651893102438, 1664.8168701442833, 1666.9166541672869, 1667.4914151183764, 1669.0736641164028, 1129.1982692288402, 1134.734770730646, 1139.145640769423, 1151.8732939376662, 1156.4506859241792, 6335.176427064452, 6337.193899738644, 6339.049142628643, 6341.524385671093, 6345.111684364193, 6532.118164047793, 6722.529422940445, 6737.730490780412, 6743.629100399525, 6746.831329216809, 6288.056791481223, 6296.590721603081, 6441.484681225491, 6442.226932768004, 6456.192975878015, 3602.2075143468396, 3644.1254099016046, 3648.2521083177885, 3648.908777280589, 3649.39784935488, 6825.453963682893, 6966.825829354205, 6981.930180698229, 6987.965060174768, 6994.370506654425, 6917.9308206917285, 6918.5502942308585, 6932.202796678345, 6932.23745388007, 6932.79201623942, 6962.547434416747, 6970.546914450544, 6978.092847929736, 6981.650917454209, 6987.336203060803, 6645.401913827779, 6765.323467653196, 6770.830729231809, 6778.627175505395, 6780.186377634715, 6670.499712521678, 6671.8665626686225, 6675.33246675348, 6678.933210667831, 6679.532432729662, 6958.234594194736, 6965.233824279427, 6971.407430368476, 6979.790606281819, 6981.616011119438, 4576.405067341224, 4601.861944141681, 4695.366125573004, 4697.271922851964, 4697.318888761042, 6680.665966701743, 6713.731450741916, 6732.091167692299, 6733.293004234485, 6760.492963027694, 6592.868142637216, 6593.538452308292, 6595.901061484177, 6599.175103112111, 6601.174853143357, 6397.744090236524, 6540.822651922699, 6579.4736421085245, 6580.5064620152725, 6594.307597702172, 6495.350464953467, 6503.902441463477, 6507.902381464187, 6517.608943463408, 6517.869642607026, 6637.568558043712, 6639.767608133692, 6642.97033959737, 6648.401643852077, 6654.800355989328, 6310.148130002391, 6461.353864613689, 6499.935000649933, 6506.934930650633, 6508.934910651023], "unit": "ms", "name": "prototype.html", "value": 4951.482993238304}, {"lowerIsBetter": false, "replicates": [917.7889085510336, 922.6493932305723, 929.2380248196478, 933.9019402962689, 951.2390087929657, 14348.210848403334, 14494.347754351053, 14514.419423223058, 14523.78214326786, 14525.782113268297, 12884.80672789909, 12972.675683107927, 13060.73878522429, 13062.085654004224, 13064.738705225913, 747.4356860570261, 755.9470837041409, 757.9621018949058, 911.3347256502757, 927.0312523413049, 888.4491615198586, 905.6286922361833, 909.3589019741066, 909.8134882349102, 910.0808183734409, 938.0947385772728, 953.7615596100975, 955.1499165742495, 955.8852937647492, 956.0153042366373, 785.4227143049853, 795.677750511044, 933.8365785987493, 950.2587981374539, 951.0632027452954, 705.5343473307644, 706.9505134640577, 710.8969199466101, 712.3944647049966, 713.0801266366382, 13443.19340839543, 13566.93216533911, 13568.660783480493, 13582.049256552045, 13625.523106691317, 12687.619371418872, 13499.66250843727, 13514.797278040738, 13618.0, 13793.310334483336, 10069.647562335356, 10220.897791022144, 10284.794304113875, 10285.845712314322, 10402.947985260102, 3894.104355998108, 3941.940870886939, 3948.0722030323136, 3953.9011524711823, 3967.563568007517, 46456.53543464556, 46457.0, 46908.7654561725, 47440.0, 47508.049839003026, 10344.502029843918, 10539.156867450585, 10637.734056648645, 10696.78606427867, 10707.089897358655, 18006.0, 18168.45494635163, 18204.36284730028, 18230.908845455953, 18287.26850925948, 7832.569208693462, 7940.722074727357, 8230.0, 8241.546714930617, 8399.202075802788, 4739.3838800955655, 4739.668223224341, 4767.95232047682, 4771.498992605796, 4783.067301876101, 3132.9216769580944, 3151.826649534252, 3154.179913222533, 3158.6209654841105, 3162.0197738701077, 556.3518500946352, 574.6408494690818, 578.1732123064058, 582.237269177379, 587.0724255676022, 648.4520580109894, 652.8890088684935, 667.4193451696958, 676.5129107042922, 685.7908337956871, 8913.37606367561, 9125.543722813833, 9208.309376796768, 9209.953950230207, 9223.216026637676, 497.40311626048884, 614.9354317796567, 685.1025157044288, 703.7888633409957, 733.1385621894253, 4165.354370072643, 4287.249731297011, 4529.184746745552, 4556.863294101182, 4569.862904112882, 60361.69819150876, 61115.69442152761, 61451.692741536004, 61960.69019654873, 61980.38019619836, 52320.47679523156, 52416.737916310936, 52670.20994685083, 54352.18471722928, 54674.0, 50075.0, 50195.24707129396, 50348.49651503511, 50573.0, 51217.23174152391, 8693.869591956, 8752.0, 8754.430961987451, 8927.0, 9123.178913897782, 42518.5748142527, 43110.137797244504, 43895.34156987584, 44653.330200046374, 45661.54338456573, 52714.0, 53449.465505346, 53479.73260133674, 54610.0, 55290.0, 11747.941260293645, 12257.0, 12342.81485777696, 12384.133110682526, 12401.317927514074, 13092.607221783363, 13213.867861321263, 13270.668233293858, 13372.86627133755, 13555.864441355854, 10391.792164156826, 10522.579096836042, 10742.51658675377, 10890.94554527254, 11154.16343774204, 11827.586034488751, 12240.16300387181, 12501.374931253218, 12541.0, 12651.734826517275, 11195.72010699739, 11666.533338666359, 11847.585334513251, 11856.881431185579, 12253.081018923438, 9764.658236961672, 10354.896451035393, 10376.481175941324, 10425.061744443037, 11126.944365278121, 10499.895001049892, 10775.51510182028, 10793.892061079603, 10857.457127143769, 10862.728431788952], "unit": "ms", "name": "yui.html", "value": 6724.909664511547}], "name": "dromaeo_css", "value": 7462.953814604482}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 103.0, 108.0, 109.0, 108.0, 98.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.0}, {"lowerIsBetter": true, "replicates": [159.0, 129.0, 155.0, 160.0, 121.0, 148.0, 149.0, 122.0, 161.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.5}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 159.0, 158.0, 161.0, 160.0, 161.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 181.0, 77.0, 73.0, 72.0, 78.0, 146.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 91.6}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 145.0, 91.0, 92.0, 93.0, 141.0, 92.0, 92.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 147.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 97.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 57.0, 56.0, 58.0, 56.0, 57.0, 56.0, 59.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.3}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 65.0, 64.0, 64.0, 67.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.6}, {"lowerIsBetter": true, "replicates": [79.0, 78.0, 81.0, 81.0, 80.0, 89.0, 79.0, 79.0, 79.0, 81.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.6}, {"lowerIsBetter": true, "replicates": [116.0, 120.0, 114.0, 112.0, 123.0, 113.0, 112.0, 113.0, 111.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.0}, {"lowerIsBetter": true, "replicates": [138.0, 143.0, 143.0, 144.0, 142.0, 141.0, 143.0, 141.0, 141.0, 131.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.7}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 63.0, 65.0, 63.0, 65.0, 63.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.9}], "name": "kraken", "value": 1479.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.49199663016006], "unit": "score", "name": "Richards", "value": 209.51736999999997}, {"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "DeltaBlue", "value": 401.33626}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5154.639175257732], "unit": "score", "name": "Decrypt", "value": 51.63911399999997}, {"lowerIsBetter": false, "replicates": [925.0693802035153], "unit": "score", "name": "RayTrace", "value": 799.928109}, {"lowerIsBetter": false, "replicates": [542.0054200542005], "unit": "score", "name": "Earley", "value": 1229.624235}, {"lowerIsBetter": false, "replicates": [10557.894736842105], "unit": "score", "name": "Boyer", "value": 63.12461116650046}, {"lowerIsBetter": false, "replicates": [25100.0], "unit": "score", "name": "RegExp", "value": 36.294223107569735}, {"lowerIsBetter": false, "replicates": [499.7501249375312], "unit": "score", "name": "Splay", "value": 163.06349100000006}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23372.728536720264}]} 15:26:11 INFO - RETURN: dromaeo_css: 7462.96 15:26:11 INFO - RETURN: kraken: 1479.80 15:26:11 INFO - RETURN: v8_7: 23372.73 15:26:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23372.73"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1479.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7462.96"}}} 15:26:11 INFO - Return code: 0 15:26:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:26:12 INFO - # TBPL SUCCESS # 15:26:12 INFO - Running post-action listener: _resource_record_post_action 15:26:12 INFO - Running post-run listener: _resource_record_post_run 15:26:13 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 0; Write bytes: 273817600; Read time: 0; Write time: 132968 15:26:13 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 14016512; Read time: 0; Write time: 2368 15:26:13 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 0; Write bytes: 259801088; Read time: 0; Write time: 130600 15:26:13 INFO - Running post-run listener: _upload_blobber_files 15:26:13 INFO - Blob upload gear active. 15:26:13 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:26:13 INFO - Copying logs to upload dir... 15:26:13 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.386053 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-02-11 15:26:12.972949) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-11 15:26:12.976655) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009857 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455224373/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-11 15:26:13.027065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:26:13.027394) ========= 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=0e2a256762fed810e2f0f810000001b3-1455231897.121004-1212108392 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004577 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 15:26:13.077086) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 15:26:13.077400) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 15:26:13.077682) ========= ========= Total master_lag: 0.26 =========