builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1454951515.6 results: success (0) buildid: 20160208064504 builduid: 21aefd27dccd4297b89795c2a1061e72 revision: 0558da46f20c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.601626) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.602067) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.602350) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004377 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.618356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.618623) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.628823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.629191) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.677434) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.677742) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.678222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.678510) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-08 09:11:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2016-02-08 09:11:57 (37.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163352 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.854311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.854632) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019667 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.903376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:55.903701) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0558da46f20c --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 0558da46f20c --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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-08 09:11:57,286 Setting DEBUG logging. 2016-02-08 09:11:57,286 attempt 1/10 2016-02-08 09:11:57,286 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0558da46f20c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-08 09:11:57,556 unpacking tar archive at: mozilla-beta-0558da46f20c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.543095 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:56.458262) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:56.458670) ========= echo 0558da46f20c in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'0558da46f20c'] 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 0558da46f20c program finished with exit code 0 elapsedTime=0.004691 script_repo_revision: '0558da46f20c' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:56.509330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:56.509627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-08 09:11:56.519198) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-08 09:11:56.519532) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:57 INFO - MultiFileLogger online at 20160208 09:11:57 in /builds/slave/test-pgo 09:11:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:11:57 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:11:57 INFO - {'append_to_log': False, 09:11:57 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:11:57 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:11:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:11:57 INFO - 'branch': 'Mozilla-Beta', 09:11:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:57 INFO - 'config_files': ('talos/linux_config.py',), 09:11:57 INFO - 'default_actions': ('clobber', 09:11:57 INFO - 'read-buildbot-config', 09:11:57 INFO - 'download-and-extract', 09:11:57 INFO - 'populate-webroot', 09:11:57 INFO - 'create-virtualenv', 09:11:57 INFO - 'install', 09:11:57 INFO - 'run-tests'), 09:11:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:57 INFO - 'download_minidump_stackwalk': True, 09:11:57 INFO - 'download_symbols': 'ondemand', 09:11:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:57 INFO - 'installer_path': 'installer.exe', 09:11:57 INFO - 'log_level': 'info', 09:11:57 INFO - 'log_name': 'talos', 09:11:57 INFO - 'log_to_console': True, 09:11:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:57 INFO - 'opt_config_files': (), 09:11:57 INFO - 'pip_index': False, 09:11:57 INFO - 'sps_profile': False, 09:11:57 INFO - 'sps_profile_interval': 0, 09:11:57 INFO - 'suite': 'dromaeojs', 09:11:57 INFO - 'system_bits': '32', 09:11:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:57 INFO - 'title': 'talos-linux64-ix-005', 09:11:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:57 INFO - 'use_talos_json': True, 09:11:57 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:11:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:57 INFO - 'work_dir': 'build'} 09:11:57 INFO - ##### 09:11:57 INFO - ##### Running clobber step. 09:11:57 INFO - ##### 09:11:57 INFO - Running pre-action listener: _resource_record_pre_action 09:11:57 INFO - Running main action method: clobber 09:11:57 INFO - rmtree: /builds/slave/test-pgo/build 09:11:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:11:58 INFO - Running post-action listener: _resource_record_post_action 09:11:58 INFO - ##### 09:11:58 INFO - ##### Running read-buildbot-config step. 09:11:58 INFO - ##### 09:11:58 INFO - Running pre-action listener: _resource_record_pre_action 09:11:58 INFO - Running main action method: read_buildbot_config 09:11:58 INFO - Using buildbot properties: 09:11:58 INFO - { 09:11:58 INFO - "properties": { 09:11:58 INFO - "buildnumber": 103, 09:11:58 INFO - "product": "firefox", 09:11:58 INFO - "script_repo_revision": "production", 09:11:58 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:11:58 INFO - "repository": "", 09:11:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:11:58 INFO - "buildid": "20160208064504", 09:11:58 INFO - "slavename": "talos-linux64-ix-005", 09:11:58 INFO - "pgo_build": "True", 09:11:58 INFO - "basedir": "/builds/slave/test-pgo", 09:11:58 INFO - "project": "", 09:11:58 INFO - "platform": "ubuntu64_hw", 09:11:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:11:58 INFO - "slavebuilddir": "test", 09:11:58 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:11:58 INFO - "branch": "mozilla-beta", 09:11:58 INFO - "repo_path": "releases/mozilla-beta", 09:11:58 INFO - "stage_platform": "linux64-pgo", 09:11:58 INFO - "builduid": "21aefd27dccd4297b89795c2a1061e72", 09:11:58 INFO - "revision": "0558da46f20c" 09:11:58 INFO - }, 09:11:58 INFO - "sourcestamp": { 09:11:58 INFO - "repository": "", 09:11:58 INFO - "hasPatch": false, 09:11:58 INFO - "project": "", 09:11:58 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:11:58 INFO - "changes": [ 09:11:58 INFO - { 09:11:58 INFO - "category": null, 09:11:58 INFO - "files": [ 09:11:58 INFO - { 09:11:58 INFO - "url": null, 09:11:58 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:11:58 INFO - } 09:11:58 INFO - ], 09:11:58 INFO - "repository": "", 09:11:58 INFO - "rev": "0558da46f20c", 09:11:58 INFO - "who": "sendchange", 09:11:58 INFO - "when": 1454951377, 09:11:58 INFO - "number": 7579030, 09:11:58 INFO - "comments": "Bug 1245251 - Repair add-on manager for external XUL apps. r=Mossop, a=sylvestre", 09:11:58 INFO - "project": "", 09:11:58 INFO - "at": "Mon 08 Feb 2016 09:09:37", 09:11:58 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:11:58 INFO - "revlink": "", 09:11:58 INFO - "properties": [ 09:11:58 INFO - [ 09:11:58 INFO - "buildid", 09:11:58 INFO - "20160208064504", 09:11:58 INFO - "Change" 09:11:58 INFO - ], 09:11:58 INFO - [ 09:11:58 INFO - "builduid", 09:11:58 INFO - "21aefd27dccd4297b89795c2a1061e72", 09:11:58 INFO - "Change" 09:11:58 INFO - ], 09:11:58 INFO - [ 09:11:58 INFO - "pgo_build", 09:11:58 INFO - "True", 09:11:58 INFO - "Change" 09:11:58 INFO - ] 09:11:58 INFO - ], 09:11:58 INFO - "revision": "0558da46f20c" 09:11:58 INFO - } 09:11:58 INFO - ], 09:11:58 INFO - "revision": "0558da46f20c" 09:11:58 INFO - } 09:11:58 INFO - } 09:11:58 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:11:58 INFO - Running post-action listener: _resource_record_post_action 09:11:58 INFO - ##### 09:11:58 INFO - ##### Running download-and-extract step. 09:11:58 INFO - ##### 09:11:58 INFO - Running pre-action listener: _resource_record_pre_action 09:11:58 INFO - Running main action method: download_and_extract 09:11:58 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/test_packages.json 09:11:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:11:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:11:58 INFO - Downloaded 1270 bytes. 09:11:58 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:11:58 INFO - Using the following test package requirements: 09:11:58 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:11:58 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:58 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'jsshell-linux-x86_64.zip'], 09:11:58 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:58 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:11:58 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:11:58 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:11:58 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:58 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:11:58 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:11:58 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:58 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:58 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:11:58 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:11:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:59 INFO - Downloaded 21582194 bytes. 09:11:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:11:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:11:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:12:00 INFO - Return code: 0 09:12:00 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:12:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:12:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:00 INFO - Downloaded 21582194 bytes. 09:12:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:12:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:12:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:12:02 INFO - Return code: 0 09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:12:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:12:02 INFO - Downloaded 11132038 bytes. 09:12:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:12:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:12:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:12:02 INFO - Return code: 0 09:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:12:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:12:04 INFO - Downloaded 57947558 bytes. 09:12:04 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:12:04 INFO - mkdir: /builds/slave/test-pgo/properties 09:12:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:12:04 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:12:04 INFO - Contents: 09:12:04 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:12:04 INFO - Running post-action listener: _resource_record_post_action 09:12:04 INFO - Running post-action listener: set_extra_try_arguments 09:12:04 INFO - ##### 09:12:04 INFO - ##### Running populate-webroot step. 09:12:04 INFO - ##### 09:12:04 INFO - Running pre-action listener: _resource_record_pre_action 09:12:04 INFO - Running main action method: populate_webroot 09:12:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:04 INFO - '--tppagecycles', 09:12:04 INFO - '7'], 09:12:04 INFO - 'tests': ['tsvgm']}}, 09:12:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:12:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:12:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:12:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'tests': ['damp', 'tps']}, 09:12:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['damp', 'tps']}, 09:12:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:12:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:04 INFO - 'other': {'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'other_l64': {'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'other_nol64': {'tests': ['a11yr', 09:12:04 INFO - 'ts_paint', 09:12:04 INFO - 'tpaint', 09:12:04 INFO - 'sessionrestore', 09:12:04 INFO - 'sessionrestore_no_auto_restore']}, 09:12:04 INFO - 'svgr': {'tests': ['tsvgx', 09:12:04 INFO - 'tsvgr_opacity', 09:12:04 INFO - 'tart', 09:12:04 INFO - 'tscrollx', 09:12:04 INFO - 'cart']}, 09:12:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['tsvgx', 09:12:04 INFO - 'tsvgr_opacity', 09:12:04 INFO - 'tart', 09:12:04 INFO - 'tscrollx', 09:12:04 INFO - 'cart']}, 09:12:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'tests': ['tp5o']}, 09:12:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'talos_options': ['--e10s'], 09:12:04 INFO - 'tests': ['tp5o']}, 09:12:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'talos_options': ['--xperf_path', 09:12:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:04 INFO - 'tests': ['tp5n']}, 09:12:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:04 INFO - 'talos_options': ['--e10s', 09:12:04 INFO - '--xperf_path', 09:12:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:04 INFO - 'tests': ['tp5n']}}, 09:12:04 INFO - 'talos.zip': {'path': '', 09:12:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:04 INFO - Running post-action listener: _resource_record_post_action 09:12:04 INFO - ##### 09:12:04 INFO - ##### Running create-virtualenv step. 09:12:04 INFO - ##### 09:12:04 INFO - Running pre-action listener: _resource_record_pre_action 09:12:04 INFO - Running main action method: create_virtualenv 09:12:04 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:12:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:12:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:12:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:04 INFO - Using real prefix '/usr' 09:12:04 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:12:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:07 INFO - Installing pip.................done. 09:12:07 INFO - Return code: 0 09:12:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:07 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:12:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:12:07 INFO - Using env: {'DISPLAY': ':0', 09:12:07 INFO - 'HOME': '/home/cltbld', 09:12:07 INFO - 'LANG': 'en_US.UTF-8', 09:12:07 INFO - 'LANGUAGE': 'en_US:en', 09:12:07 INFO - 'LOGNAME': 'cltbld', 09:12:07 INFO - 'MAIL': '/var/mail/cltbld', 09:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:07 INFO - 'MOZ_NO_REMOTE': '1', 09:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:07 INFO - 'NO_EM_RESTART': '1', 09:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:07 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:07 INFO - 'SHELL': '/bin/bash', 09:12:07 INFO - 'SHLVL': '1', 09:12:07 INFO - 'TERM': 'linux', 09:12:07 INFO - 'TMOUT': '86400', 09:12:07 INFO - 'USER': 'cltbld', 09:12:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:07 INFO - '_': '/tools/buildbot/bin/python'} 09:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:12:07 INFO - Cleaning up... 09:12:07 INFO - Return code: 0 09:12:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:07 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:12:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:12:07 INFO - Using env: {'DISPLAY': ':0', 09:12:07 INFO - 'HOME': '/home/cltbld', 09:12:07 INFO - 'LANG': 'en_US.UTF-8', 09:12:07 INFO - 'LANGUAGE': 'en_US:en', 09:12:07 INFO - 'LOGNAME': 'cltbld', 09:12:07 INFO - 'MAIL': '/var/mail/cltbld', 09:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:07 INFO - 'MOZ_NO_REMOTE': '1', 09:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:07 INFO - 'NO_EM_RESTART': '1', 09:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:07 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:07 INFO - 'SHELL': '/bin/bash', 09:12:07 INFO - 'SHLVL': '1', 09:12:07 INFO - 'TERM': 'linux', 09:12:07 INFO - 'TMOUT': '86400', 09:12:07 INFO - 'USER': 'cltbld', 09:12:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:07 INFO - '_': '/tools/buildbot/bin/python'} 09:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:07 INFO - Downloading/unpacking psutil>=0.7.1 09:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:09 INFO - Installing collected packages: psutil 09:12:09 INFO - Running setup.py install for psutil 09:12:09 INFO - building 'psutil._psutil_linux' extension 09:12:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:12:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:12:10 INFO - building 'psutil._psutil_posix' extension 09:12:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:12:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:12:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:10 INFO - Successfully installed psutil 09:12:10 INFO - Cleaning up... 09:12:10 INFO - Return code: 0 09:12:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:10 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:12:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:12:10 INFO - Using env: {'DISPLAY': ':0', 09:12:10 INFO - 'HOME': '/home/cltbld', 09:12:10 INFO - 'LANG': 'en_US.UTF-8', 09:12:10 INFO - 'LANGUAGE': 'en_US:en', 09:12:10 INFO - 'LOGNAME': 'cltbld', 09:12:10 INFO - 'MAIL': '/var/mail/cltbld', 09:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:10 INFO - 'MOZ_NO_REMOTE': '1', 09:12:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:10 INFO - 'NO_EM_RESTART': '1', 09:12:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:10 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:10 INFO - 'SHELL': '/bin/bash', 09:12:10 INFO - 'SHLVL': '1', 09:12:10 INFO - 'TERM': 'linux', 09:12:10 INFO - 'TMOUT': '86400', 09:12:10 INFO - 'USER': 'cltbld', 09:12:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:10 INFO - '_': '/tools/buildbot/bin/python'} 09:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:12:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:12:12 INFO - Installing collected packages: mozsystemmonitor 09:12:12 INFO - Running setup.py install for mozsystemmonitor 09:12:12 INFO - Successfully installed mozsystemmonitor 09:12:12 INFO - Cleaning up... 09:12:12 INFO - Return code: 0 09:12:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:12 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:12:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:12:12 INFO - Using env: {'DISPLAY': ':0', 09:12:12 INFO - 'HOME': '/home/cltbld', 09:12:12 INFO - 'LANG': 'en_US.UTF-8', 09:12:12 INFO - 'LANGUAGE': 'en_US:en', 09:12:12 INFO - 'LOGNAME': 'cltbld', 09:12:12 INFO - 'MAIL': '/var/mail/cltbld', 09:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:12 INFO - 'MOZ_NO_REMOTE': '1', 09:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:12 INFO - 'NO_EM_RESTART': '1', 09:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:12 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:12 INFO - 'SHELL': '/bin/bash', 09:12:12 INFO - 'SHLVL': '1', 09:12:12 INFO - 'TERM': 'linux', 09:12:12 INFO - 'TMOUT': '86400', 09:12:12 INFO - 'USER': 'cltbld', 09:12:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:12 INFO - '_': '/tools/buildbot/bin/python'} 09:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:12 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:12:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:15 INFO - Downloading docopt-0.6.1.tar.gz 09:12:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:15 INFO - Running setup.py install for blobuploader 09:12:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:12:15 INFO - Running setup.py install for requests 09:12:15 INFO - Running setup.py install for docopt 09:12:15 INFO - Successfully installed blobuploader requests docopt 09:12:15 INFO - Cleaning up... 09:12:15 INFO - Return code: 0 09:12:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:15 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:12:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:12:15 INFO - Using env: {'DISPLAY': ':0', 09:12:15 INFO - 'HOME': '/home/cltbld', 09:12:15 INFO - 'LANG': 'en_US.UTF-8', 09:12:15 INFO - 'LANGUAGE': 'en_US:en', 09:12:15 INFO - 'LOGNAME': 'cltbld', 09:12:15 INFO - 'MAIL': '/var/mail/cltbld', 09:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:15 INFO - 'MOZ_NO_REMOTE': '1', 09:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:15 INFO - 'NO_EM_RESTART': '1', 09:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:15 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:15 INFO - 'SHELL': '/bin/bash', 09:12:15 INFO - 'SHLVL': '1', 09:12:15 INFO - 'TERM': 'linux', 09:12:15 INFO - 'TMOUT': '86400', 09:12:15 INFO - 'USER': 'cltbld', 09:12:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:15 INFO - '_': '/tools/buildbot/bin/python'} 09:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:12:16 INFO - Running setup.py (path:/tmp/pip-PbVJeQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:12:16 INFO - Running setup.py (path:/tmp/pip-RFXuhA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:12:16 INFO - Running setup.py (path:/tmp/pip-hCqks5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:12:16 INFO - Running setup.py (path:/tmp/pip-2lWG2A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:12:16 INFO - Running setup.py (path:/tmp/pip-RPd4Sz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:12:16 INFO - Running setup.py (path:/tmp/pip-7gGCvS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:12:16 INFO - Running setup.py (path:/tmp/pip-ckOK6I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:12:16 INFO - Running setup.py (path:/tmp/pip-Xqght9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:12:16 INFO - Running setup.py (path:/tmp/pip-8YRcfZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:12:16 INFO - Running setup.py (path:/tmp/pip-d5DCV3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:12:16 INFO - Running setup.py (path:/tmp/pip-noimtZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:12:16 INFO - Running setup.py (path:/tmp/pip-sxr2_5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:12:16 INFO - Running setup.py (path:/tmp/pip-voYxWk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:12:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:12:17 INFO - Running setup.py (path:/tmp/pip-N3Ipmt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:12:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:12:17 INFO - Running setup.py (path:/tmp/pip-WWSxtq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:12:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:12:17 INFO - Running setup.py (path:/tmp/pip-cW3P4S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:12:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:12:17 INFO - Running setup.py (path:/tmp/pip-BIS155-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:12:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:17 INFO - Running setup.py install for manifestparser 09:12:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:12:17 INFO - Running setup.py install for mozcrash 09:12:17 INFO - Running setup.py install for mozdebug 09:12:17 INFO - Running setup.py install for mozdevice 09:12:17 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:12:17 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:12:17 INFO - Running setup.py install for mozfile 09:12:17 INFO - Running setup.py install for mozhttpd 09:12:17 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:12:17 INFO - Running setup.py install for mozinfo 09:12:17 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:12:17 INFO - Running setup.py install for mozInstall 09:12:18 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Running setup.py install for mozleak 09:12:18 INFO - Running setup.py install for mozlog 09:12:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Running setup.py install for moznetwork 09:12:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Running setup.py install for mozprocess 09:12:18 INFO - Running setup.py install for mozprofile 09:12:18 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Running setup.py install for mozrunner 09:12:18 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:12:18 INFO - Running setup.py install for mozscreenshot 09:12:18 INFO - Running setup.py install for moztest 09:12:18 INFO - Running setup.py install for mozversion 09:12:19 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:12:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:19 INFO - Cleaning up... 09:12:19 INFO - Return code: 0 09:12:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:12:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:12:19 INFO - Using env: {'DISPLAY': ':0', 09:12:19 INFO - 'HOME': '/home/cltbld', 09:12:19 INFO - 'LANG': 'en_US.UTF-8', 09:12:19 INFO - 'LANGUAGE': 'en_US:en', 09:12:19 INFO - 'LOGNAME': 'cltbld', 09:12:19 INFO - 'MAIL': '/var/mail/cltbld', 09:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:19 INFO - 'MOZ_NO_REMOTE': '1', 09:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:19 INFO - 'NO_EM_RESTART': '1', 09:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:19 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:19 INFO - 'SHELL': '/bin/bash', 09:12:19 INFO - 'SHLVL': '1', 09:12:19 INFO - 'TERM': 'linux', 09:12:19 INFO - 'TMOUT': '86400', 09:12:19 INFO - 'USER': 'cltbld', 09:12:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:19 INFO - '_': '/tools/buildbot/bin/python'} 09:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:12:19 INFO - Running setup.py (path:/tmp/pip-vGxt45-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:12:19 INFO - Running setup.py (path:/tmp/pip-7pzHlw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:12:19 INFO - Running setup.py (path:/tmp/pip-edvhps-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:12:19 INFO - Running setup.py (path:/tmp/pip-L60tPV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:12:19 INFO - Running setup.py (path:/tmp/pip-JsrFuh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:12:19 INFO - Running setup.py (path:/tmp/pip-YR4OD6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:12:19 INFO - Running setup.py (path:/tmp/pip-yVtbOw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:12:19 INFO - Running setup.py (path:/tmp/pip-HrXY8F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:12:19 INFO - Running setup.py (path:/tmp/pip-NGGy9b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:12:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:12:19 INFO - Running setup.py (path:/tmp/pip-XgF3zr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:12:20 INFO - Running setup.py (path:/tmp/pip-bX1oKD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:12:20 INFO - Running setup.py (path:/tmp/pip-7jfcVq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:12:20 INFO - Running setup.py (path:/tmp/pip-M6Ysms-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:12:20 INFO - Running setup.py (path:/tmp/pip-XDq_MJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:12:20 INFO - Running setup.py (path:/tmp/pip-qDpZqT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:12:20 INFO - Running setup.py (path:/tmp/pip-7qBtAH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:12:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:12:20 INFO - Running setup.py (path:/tmp/pip-Op9hGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:12:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:22 INFO - Downloading blessings-1.6.tar.gz 09:12:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:22 INFO - Installing collected packages: blessings 09:12:22 INFO - Running setup.py install for blessings 09:12:22 INFO - Successfully installed blessings 09:12:22 INFO - Cleaning up... 09:12:22 INFO - Return code: 0 09:12:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:12:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:12:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:12:22 INFO - Reading from file tmpfile_stdout 09:12:22 INFO - Current package versions: 09:12:22 INFO - argparse == 1.2.1 09:12:22 INFO - blessings == 1.6 09:12:22 INFO - blobuploader == 1.2.4 09:12:22 INFO - docopt == 0.6.1 09:12:22 INFO - manifestparser == 1.1 09:12:22 INFO - mozInstall == 1.12 09:12:22 INFO - mozcrash == 0.16 09:12:22 INFO - mozdebug == 0.1 09:12:22 INFO - mozdevice == 0.47 09:12:22 INFO - mozfile == 1.2 09:12:22 INFO - mozhttpd == 0.7 09:12:22 INFO - mozinfo == 0.9 09:12:22 INFO - mozleak == 0.1 09:12:22 INFO - mozlog == 3.1 09:12:22 INFO - moznetwork == 0.27 09:12:22 INFO - mozprocess == 0.22 09:12:22 INFO - mozprofile == 0.27 09:12:22 INFO - mozrunner == 6.11 09:12:22 INFO - mozscreenshot == 0.1 09:12:22 INFO - mozsystemmonitor == 0.0 09:12:22 INFO - moztest == 0.7 09:12:22 INFO - mozversion == 1.4 09:12:22 INFO - psutil == 3.1.1 09:12:22 INFO - requests == 1.2.3 09:12:22 INFO - wsgiref == 0.1.2 09:12:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:12:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:12:22 INFO - Using env: {'DISPLAY': ':0', 09:12:22 INFO - 'HOME': '/home/cltbld', 09:12:22 INFO - 'LANG': 'en_US.UTF-8', 09:12:22 INFO - 'LANGUAGE': 'en_US:en', 09:12:22 INFO - 'LOGNAME': 'cltbld', 09:12:22 INFO - 'MAIL': '/var/mail/cltbld', 09:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:22 INFO - 'MOZ_NO_REMOTE': '1', 09:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:22 INFO - 'NO_EM_RESTART': '1', 09:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:22 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:22 INFO - 'SHELL': '/bin/bash', 09:12:22 INFO - 'SHLVL': '1', 09:12:22 INFO - 'TERM': 'linux', 09:12:22 INFO - 'TMOUT': '86400', 09:12:22 INFO - 'USER': 'cltbld', 09:12:22 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:22 INFO - '_': '/tools/buildbot/bin/python'} 09:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:12:22 INFO - Cleaning up... 09:12:22 INFO - Return code: 0 09:12:22 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:12:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:12:22 INFO - Using env: {'DISPLAY': ':0', 09:12:22 INFO - 'HOME': '/home/cltbld', 09:12:23 INFO - 'LANG': 'en_US.UTF-8', 09:12:23 INFO - 'LANGUAGE': 'en_US:en', 09:12:23 INFO - 'LOGNAME': 'cltbld', 09:12:23 INFO - 'MAIL': '/var/mail/cltbld', 09:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:23 INFO - 'MOZ_NO_REMOTE': '1', 09:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:23 INFO - 'NO_EM_RESTART': '1', 09:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:23 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:23 INFO - 'SHELL': '/bin/bash', 09:12:23 INFO - 'SHLVL': '1', 09:12:23 INFO - 'TERM': 'linux', 09:12:23 INFO - 'TMOUT': '86400', 09:12:23 INFO - 'USER': 'cltbld', 09:12:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:23 INFO - '_': '/tools/buildbot/bin/python'} 09:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:23 INFO - Downloading/unpacking jsonschema 09:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:24 INFO - Downloading/unpacking functools32 (from jsonschema) 09:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:25 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:25 INFO - warning: no files found matching '*.txt' 09:12:25 INFO - no previously-included directories found matching 'build' 09:12:25 INFO - no previously-included directories found matching 'dist' 09:12:25 INFO - no previously-included directories found matching '.git*' 09:12:25 INFO - Installing collected packages: jsonschema, functools32 09:12:25 INFO - Running setup.py install for functools32 09:12:25 INFO - warning: no files found matching '*.txt' 09:12:25 INFO - no previously-included directories found matching 'build' 09:12:25 INFO - no previously-included directories found matching 'dist' 09:12:25 INFO - no previously-included directories found matching '.git*' 09:12:25 INFO - Successfully installed jsonschema functools32 09:12:25 INFO - Cleaning up... 09:12:25 INFO - Return code: 0 09:12:25 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 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 0x7f7e8674ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28958a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2962600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:12:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:12:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:12:25 INFO - Using env: {'DISPLAY': ':0', 09:12:25 INFO - 'HOME': '/home/cltbld', 09:12:25 INFO - 'LANG': 'en_US.UTF-8', 09:12:25 INFO - 'LANGUAGE': 'en_US:en', 09:12:25 INFO - 'LOGNAME': 'cltbld', 09:12:25 INFO - 'MAIL': '/var/mail/cltbld', 09:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:25 INFO - 'MOZ_NO_REMOTE': '1', 09:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:25 INFO - 'NO_EM_RESTART': '1', 09:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:25 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:25 INFO - 'SHELL': '/bin/bash', 09:12:25 INFO - 'SHLVL': '1', 09:12:25 INFO - 'TERM': 'linux', 09:12:25 INFO - 'TMOUT': '86400', 09:12:25 INFO - 'USER': 'cltbld', 09:12:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:25 INFO - '_': '/tools/buildbot/bin/python'} 09:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:25 INFO - Downloading/unpacking flake8 09:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:12:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:12:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:12:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:12:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:12:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:12:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:12:27 INFO - no previously-included directories found matching 'docs/_build' 09:12:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:12:27 INFO - Running setup.py install for pep8 09:12:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:12:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:12:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:12:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:12:27 INFO - no previously-included directories found matching 'docs/_build' 09:12:27 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:12:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:12:27 INFO - Cleaning up... 09:12:27 INFO - Return code: 0 09:12:27 INFO - Running post-action listener: _resource_record_post_action 09:12:27 INFO - Running post-action listener: _start_resource_monitoring 09:12:27 INFO - Starting resource monitoring. 09:12:27 INFO - ##### 09:12:27 INFO - ##### Running install step. 09:12:27 INFO - ##### 09:12:27 INFO - Running pre-action listener: _resource_record_pre_action 09:12:27 INFO - Running main action method: install 09:12:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:12:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:12:28 INFO - Reading from file tmpfile_stdout 09:12:28 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:12:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:12:28 INFO - Reading from file tmpfile_stdout 09:12:28 INFO - Output received: 09:12:28 INFO - Usage: mozinstall [options] installer 09:12:28 INFO - Options: 09:12:28 INFO - -h, --help show this help message and exit 09:12:28 INFO - -d DEST, --destination=DEST 09:12:28 INFO - Directory to install application into. [default: 09:12:28 INFO - "/builds/slave/test-pgo"] 09:12:28 INFO - --app=APP Application being installed. [default: firefox] 09:12:28 INFO - mkdir: /builds/slave/test-pgo/build/application 09:12:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:12:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:12:42 INFO - Reading from file tmpfile_stdout 09:12:42 INFO - Output received: 09:12:42 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:12:42 INFO - Running post-action listener: _resource_record_post_action 09:12:42 INFO - ##### 09:12:42 INFO - ##### Running run-tests step. 09:12:42 INFO - ##### 09:12:42 INFO - Running pre-action listener: _resource_record_pre_action 09:12:42 INFO - Running main action method: run_tests 09:12:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:12:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:12:42 INFO - Python 2.7.3 09:12:42 INFO - Return code: 0 09:12:42 INFO - grabbing minidump binary from tooltool 09:12:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:42 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 0x29f1350>, '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 0x29f2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29f26e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:12:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:12:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:12:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:42 INFO - Return code: 0 09:12:42 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:12:42 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:12:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:12:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:12:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:12:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:12:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:12:43 INFO - Return code: 0 09:12:43 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/1454942704/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:12:43 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/1454942704/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost 09:12:43 INFO - Using env: {'DISPLAY': ':0', 09:12:43 INFO - 'HOME': '/home/cltbld', 09:12:43 INFO - 'LANG': 'en_US.UTF-8', 09:12:43 INFO - 'LANGUAGE': 'en_US:en', 09:12:43 INFO - 'LOGNAME': 'cltbld', 09:12:43 INFO - 'MAIL': '/var/mail/cltbld', 09:12:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:12:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:43 INFO - 'MOZ_NO_REMOTE': '1', 09:12:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:43 INFO - 'NO_EM_RESTART': '1', 09:12:43 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:12:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:12:43 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:12:43 INFO - 'SHELL': '/bin/bash', 09:12:43 INFO - 'SHLVL': '1', 09:12:43 INFO - 'TERM': 'linux', 09:12:43 INFO - 'TMOUT': '86400', 09:12:43 INFO - 'USER': 'cltbld', 09:12:43 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937', 09:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:43 INFO - '_': '/tools/buildbot/bin/python'} 09:12:43 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/1454942704/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] with output_timeout 3600 09:12:44 INFO - mozversion INFO | application_buildid: 20160208064504 09:12:44 INFO - mozversion INFO | application_changeset: 0558da46f20cb77556c1b3c5d7989ea0bb7be25e 09:12:44 INFO - mozversion INFO | application_display_name: Nightly 09:12:44 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:44 INFO - mozversion INFO | application_name: Firefox 09:12:44 INFO - mozversion INFO | application_remotingname: firefox 09:12:44 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:12:44 INFO - mozversion INFO | application_vendor: Mozilla 09:12:44 INFO - mozversion INFO | application_version: 45.0 09:12:44 INFO - mozversion INFO | platform_buildid: 20160208064504 09:12:44 INFO - mozversion INFO | platform_changeset: 0558da46f20cb77556c1b3c5d7989ea0bb7be25e 09:12:44 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:12:44 INFO - mozversion INFO | platform_version: 45.0 09:12:44 INFO - 2016-02-08 09:12:44,057 DEBUG : using testdate: 1454951564 09:12:44 INFO - 2016-02-08 09:12:44,058 DEBUG : actual date: 1454951564 09:12:44 INFO - 2016-02-08 09:12:44,066 INFO : starting webserver on 'localhost:56557' 09:12:44 INFO - 2016-02-08 09:12:44,067 INFO : Starting test suite talos-linux64-ix-005 09:12:44 INFO - 2016-02-08 09:12:44,067 INFO : Starting test dromaeo_css 09:12:44 INFO - 2016-02-08 09:12:44,068 DEBUG : operating with platform_type : linux_ 09:12:44 INFO - 2016-02-08 09:12:44,068 INFO : Initialising browser for dromaeo_css test... 09:12:44 INFO - 2016-02-08 09:12:44,082 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUABZ6W/profile http://localhost:56557/getInfo.html 09:12:48 INFO - 2016-02-08 09:12:48,023 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:12:48 INFO - 2016-02-08 09:12:48,024 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:12:48 INFO - 2016-02-08 09:12:48,024 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:12:48 INFO - 2016-02-08 09:12:48,024 DEBUG : BROWSER_OUTPUT: __metrics 09:12:48 INFO - 2016-02-08 09:12:48,140 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:48 INFO - 2016-02-08 09:12:48,902 INFO : Browser initialized. 09:12:48 INFO - 2016-02-08 09:12:48,902 INFO : Running cycle 1/1 for dromaeo_css test... 09:12:48 INFO - 2016-02-08 09:12:48,903 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpUABZ6W/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:13:00 INFO - 2016-02-08 09:13:00,674 DEBUG : BROWSER_OUTPUT: RSS: Main: 134574080 09:13:00 INFO - 2016-02-08 09:13:00,674 DEBUG : BROWSER_OUTPUT: 09:13:51 INFO - 2016-02-08 09:13:51,612 DEBUG : BROWSER_OUTPUT: 1454951631609 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:15:59 INFO - 2016-02-08 09:15:59,729 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56557/tests/dromaeo/cssquery-ext.html) 09:16:00 INFO - 2016-02-08 09:16:00,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 213585920 09:16:00 INFO - 2016-02-08 09:16:00,859 DEBUG : BROWSER_OUTPUT: 09:19:02 INFO - 2016-02-08 09:19:02,937 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-ext.html (next: http://localhost:56557/tests/dromaeo/cssquery-jquery.html) 09:19:03 INFO - 2016-02-08 09:19:03,499 DEBUG : BROWSER_OUTPUT: RSS: Main: 226844672 09:19:03 INFO - 2016-02-08 09:19:03,499 DEBUG : BROWSER_OUTPUT: 09:22:05 INFO - 2016-02-08 09:22:05,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56557/tests/dromaeo/cssquery-mootools.html) 09:22:05 INFO - 2016-02-08 09:22:05,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 218161152 09:22:05 INFO - 2016-02-08 09:22:05,748 DEBUG : BROWSER_OUTPUT: 09:25:07 INFO - 2016-02-08 09:25:07,463 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56557/tests/dromaeo/cssquery-prototype.html) 09:25:08 INFO - 2016-02-08 09:25:08,033 DEBUG : BROWSER_OUTPUT: RSS: Main: 197980160 09:25:08 INFO - 2016-02-08 09:25:08,033 DEBUG : BROWSER_OUTPUT: 09:28:10 INFO - 2016-02-08 09:28:10,361 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56557/tests/dromaeo/cssquery-yui.html) 09:28:10 INFO - 2016-02-08 09:28:10,928 DEBUG : BROWSER_OUTPUT: RSS: Main: 201297920 09:28:10 INFO - 2016-02-08 09:28:10,928 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/dromaeo/cssquery-yui.html (next: http://localhost:56557/tests/dromaeo/cssquery-dojo.html) 09:31:13 INFO - 2016-02-08 09:31:13,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 242749440 09:31:13 INFO - 2016-02-08 09:31:13,373 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,373 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:31:13 INFO - 2016-02-08 09:31:13,374 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:31:13 INFO - 2016-02-08 09:31:13,374 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:31:13 INFO - 2016-02-08 09:31:13,374 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:31:13 INFO - 2016-02-08 09:31:13,375 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3273.852676629552;3347.7656564040512;3350.698437140657;3366.562346894904;3371.6628337166303;5644.463775941289;5656.321241451026;5670.773169073237;5672.773089076437;5673.546116310696;5679.204911312409;5687.17535957286;5693.117566777146;5696.772129114841;5697.6011679182475;4930.137225985456;5165.483451654842;5179.792808287682;5243.187305967562;5261.605379596526;5115.283860259567;5140.588752899759;5143.125668636322;5148.7940482380845;5150.7424628768595;5038.2190760432;5089.389273287211;5141.974290128563;5163.173892177252;5168.095583272931;5037.848864534069;5119.820806271762;5136.43499215086;5138.229265610151;5180.4301526832205;5136.203888397305;5152.716600586966;5246;5249.291345668324;5281.656692314988;5268.236105764681;5274.736263186864;5422.40353561108;5455.618106732531;5462.371827239878;6087.421694939018;6090.120626059225;6094.756209751605;6097.7368455077885;6104.328523862371;6035.3059398169335;6106.755729770804;6124.11200375943;6124.816255512342;6135.9079613805825;5414.431484694129;5471.425500322409;5507.614466987313;5643.351014633328;5675.602707810455;6083.393013447916;6121.418465245794;6123.877522449526;6124.846878828065;6130.172426722335;5937.524998000236;5939.762409503658;5940.257467816523;5951.404859514014;5981.252343457068;5921.61509501881;5984.371640977634;6025.728842202024;6027.216461860018;6126.23422072241;6040.6677632730625;6089.147519347295;6097.115918191837;6098.878022439526;6104.2064531611495;6031.517478601701;6045.667488288186;6054.788082417094;6099.969500152471;6110.7861224856915;4455.844045458458;4506.1663592235545;4573.565511276423;4600.700954437951;4600.838970636011;4087.8160482777753;4094.590540945941;4105.91788164235;4122.546519882871;4128.772917489567;5518.668879867221;5717.999350113797;5728.455796699307;5732.942670573324;5734.025215713339;5908.940910590839;5916.349201587822;5957.970210148921;5984.132300816357;6020.879582408327;5678.943210567842;5696.202531645573;5700.315962084494;5704.258446402024;5713.057345538023;4996.125678006407;5109.897802044013;5160.948390516047;5171.095058364846;5192.870178245574;6129.246503173489;6226.875462490815;6232.439080482688;6246.469050130699;6248.125262463259;6064.696765161636;6107.877842443215;6108.17539632144;6114.57197996136;6121.112438696453;6103.115048318058;6140.824239517171;6142.283918229586;6283.403076707706;6287.937120628735;5829.213056237354;5847.06446968483;5850.444207800252;5853;5902.350741418355;5900.498457631149;5907.232059832194;5942.821715348547;5946;5957.76168953237;5667.518260947865;5702.7718891243885;5780.248567686174;5805.390434004369;5811.970940145272;5876.265466816648;6007.3992600739575;6021.397860213943;6021.54838387114;6024.608400454132;5886.911696324644;5904.291485021825;5915.260592425947;5926.614770039933;5929.792457263975;5773.3072031356505;5868.295804503487;5899.17411562373;5965.254343207099;5971.28344598651;5715.799947002001;5802.970985145047;5805.332386775406;5819.854503637443;5828.679422631796 09:31:13 INFO - 2016-02-08 09:31:13,377 DEBUG : BROWSER_OUTPUT: |1;ext.html;594.4738906068128;620.3207487800856;628.5568674084769;636.522608043967;643.1285608001158;32878.506822397656;41412.79293603528;41435.79282103593;41495.58504414955;41651.37522937158;45799.771001144945;46465;46470.30294545584;46534;46538.30192547106;8057.879131813028;8158.143394943534;8162.591870406456;8960.193582577567;9119.088091190893;10778.19163562732;10923.672289831278;11014.559417623286;11023.889761102406;11033.83449248262;10626;10737.731556711067;10739.355638661666;10743.892561074406;10745.355278683264;10643.361398316087;10747.032767051003;10764.977327153947;10767.730806729816;10772.676819695422;10420.478976051167;10620.097291730212;10630.946845265724;10634.415107169103;10638.62764803236;42645;43059.78470107629;43128.568714313085;43159.78420107879;43165.56834431647;3721.7394782365245;3764.284785890672;3875.61243875613;3958.920821583581;4186.267403204427;32361;32664.346713065843;32881.6711832881;34034.659653403396;34051.6594834051;3654.476783821582;3703.3889408247605;3766.6610005099674;3778.471014058034;3816.866409675648;271843;275185;276229;276640.99999999796;277429;63152.36847631465;64801;64929;73548;75353.24646753463;64797.676011620584;64866;65120.34879651238;65165.67417162884;65261.67369163124;16797.83202167987;16948;16969.915150424167;16975.830241697673;17008;12842.550510732079;12938.288394138224;12938.741225175443;13014.54449094296;13059;21973.340799775713;22092.779072209392;22099.447513812283;22173.33479995603;22200.222992195195;8351.749447516584;8583.914160858436;8610.268127209258;8695.608697608623;8742.38803283764;1026.3174988632625;1056.7675111475462;1090.1224514266091;1140.236041851961;1166.3060479014973;28762.568561471595;28945;29094.272643183667;29701;29983.400331993238;1152.2049785647876;1171.5372427891107;1188.1742189178437;1224.0452447091284;1266.6200139957975;14863.628409289855;15554.766678499831;15831.920840395724;15896.205189740467;17233.827661723473;403242;408233;408592;408794;408873;58348;58921.41078589245;59000.70499647474;59012.40987590155;59063.11405328924;1342.7583035053783;1499.8350181480027;1500.534834201401;1502.9098254104788;1519.050593379138;31186;31638.367232654757;32054.19864503406;32225.35549289048;32506.674933250364;49756;50504.49495504998;50595.74702126466;50627.22804701818;50651.493485064675;281894;286856;289196;289820;291353;34857.302853942274;47753.522464774906;48148.27777583409;48165;48201.276980846014;48043;48936;48950.510494894595;49394.50605493899;49466.75266623644;40346.39480407855;41610.37584436296;41613.37579936243;41754.37368439537;42198;41365.37951930783;42153.36769948392;42171.7891410541;44590.55409445864;45680.31479527743;38175.04562385852;38193.42709859299;38387.232255355295;38602.227955440176;38841.805790970866;33974.49038264379;34438.31123377568;34603.826980864935;40120.39819402653;40850.79574602108;26443;26596;26626.600600990612;26693.866530667998;26815.19554413371 09:31:13 INFO - 2016-02-08 09:31:13,380 DEBUG : BROWSER_OUTPUT: |2;jquery.html;379.74936541882363;382.84877473398024;385.12192201779953;392.8075243130865;396.2688839091876;14864.33110510027;16331.91834040831;17725.202365893532;19223.807761922377;19246.807531924693;11494.770104597903;11749.941250293758;11766.882331176686;18529.16618752156;19005.809941900578;5130.076586214489;5257.158854583267;5261.710605916673;5264.499872512124;5265.605079619044;4145.316022856225;4250.128723611668;4263.637590804769;4292.29177185764;5194.948050519484;5493.121100623901;5650.208970744099;5658.151277308416;5664.688442135681;5695.202671625955;5507.862303442426;5684.2326285951285;5690.260266165393;5690.658560486384;5690.829275121753;5471.781128754845;5495.230667706524;5503.2020357048405;5511.035568775448;5512.090505066659;13775.793363099561;13863.930680346533;14054.508092216825;14128.788068178985;14167.291635418187;2321.442853715113;2418.6734790803193;2467.679201703785;2492.0779311654624;2538.1497198438547;10591.649121759145;10611.257211995164;10612.20408469368;10737.24839261252;11510.366929818856;1801.9549511262323;1883.2372888979849;1977.1893523655233;1980.8118228768244;2021.353166986565;65253;66094;66505.99999999952;67459.32540674579;67678.66160669214;10847.51186196623;11146.498407571678;11237.438128093563;11276.830847537294;11325.093992480583;14320.785188222186;14811;14811.25943702832;14850.777238341434;14851.183184924717;7854.8429031419055;7886.605669716606;7928.524288542706;8133.471324363897;8210.794730131794;7492.250774922573;7553.962230188814;7965.8805117923275;8030.437869349206;8117;4584.426946631671;4681.578657920803;4710.646701497401;4830.2513110467935;4871.269309603517;1809.7285407188763;1836.40316897009;1883.6986082226779;1909.455805095541;1947.1960377056992;894.1684233662756;986.7385142936984;1062.877769056553;1080.7190130565953;1082.0856376361962;14703;17602.207900644502;17738.29046838138;18176.90911545434;18324.358647447276;1033.245730616654;1037.699067270483;1049.0558497352292;1065.7868426314767;1093.5297821936645;9910.950445247727;10053.69838904834;10070.395776253301;10083.19334453242;10085.49572521371;597748;599102;607534;621108;625464;81546.7381304753;82234.17765822385;83615;84185;84445;1193.0634451955173;1196.7127889306678;1196.8803119687961;1197.8682344942224;1209.0810983652311;16160;16288.3484660617;16295.67408651844;16398.18009099564;16600.169991500134;36767.264654707295;37749;38135.42796857994;38406.61593384142;38641.647542335886;49370.259446107615;49684.503154967984;50043.74978125086;53305.1343202986;53466;10662.253642244968;10781.24531282834;10859.782804343711;10884.61903833362;10886.183536234656;18233;18671.06644667734;18752.718709219098;18816;18891.244350225836;14198.3610737515;15484.458043968407;15779.605509861885;15843.841561584237;16082.195890205678;15314.310856011283;15426.84573154299;15498.302576383865;15735.921320393325;16183.595410114842;11515.424228788695;15501.922490387477;15674;15741;16164.353425862833;9929.255305852235;10116.797664046826;10249.436281004617;10252.897471025195;10288.537015834156;14784.260786960394;14874.702505949605;14997.325120369827;15001.849981500482;15208.847911521187 09:31:13 INFO - 2016-02-08 09:31:13,380 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1598.3686443854679;1600.2078970909402;1859.8140185981401;1863.79498255192;1871.8955816068521;8032.317253033492;8422.199891010352;8428.662853485868;8429.19922607352;8431.662733490653;7256.419486441086;7466.104067511893;7570.280823321776;7745.109312429072;8237.052738935025;2313.3985163857433;2374.905003799846;2399.028393500635;2404.699412573428;2408.5544174327806;2170.1861801824316;2409.819263555232;2410.7348191698898;2455.0793452455327;2460.372604985726;2146.5599552091785;2375.168690958168;2420.165043060141;2423.9272821815375;2424.5756992526235;2314.9884250578725;2357.5992081346126;2363.8227132964926;2367.2188177901253;2368.0882860098814;2348.765123487655;2358.9764102358927;2385.9045638174453;2387.9641805372935;2393.6409538569355;8156.388270879707;8231.42380033398;8236.258736713664;8237.46456480333;8241.50550966944;4907.1657818171;5031.899362012776;5033.597312215051;5034.521720436589;5035.823746168903;3191.760617953663;3207.23026473647;3241.9837900810344;3260.625028121749;3263.1515805890403;1056.4581942280554;1063.9042486176204;1075.9462026898702;1885.962280754391;1889.631521853239;10971.50628221724;11112.166587505888;11131.33212007274;11135.276207046516;11147.999999999918;4259.701820872509;4322.459692538433;4388.4733831940375;4518.4126063612175;4525.072360166158;9355.812883742286;9371.812563748686;9375.812483750287;9376.578053987587;9376.62493500266;4980.402351717817;4994.625403094782;5004.749762511859;5011.373578302712;5015.824446144367;4579.129965306581;4582.24392975162;4623.052274283831;4626.629869610423;4629.467611224685;3682.1715114099115;3705.5368078990127;3715.758475699071;3738.4392341149046;3763.9435408468894;1095.8849420618283;1098.5001824169983;1134.1153899958047;1134.3420815926743;1138.5104405105883;1128.3399211461387;1178.5168081086713;1192.8986036186848;1200.5677955935855;1212.405921098673;7783.727569535153;7843.56860372685;7847.097583777824;7856.175101614363;8057.5165490070785;1229.0229267732175;1233.2396947781556;1234.3087870792385;1237.6967642927361;1241.0815996162773;6251.78118765841;6371.681415929185;6424.903626445607;6431.903521447182;6441.227052753699;9772.951135244279;10004.249681273788;10108.24188185889;10117.140043096271;10131.392116473035;9555.092266234697;10001.24990625706;10006.79986400268;10012.098911098035;10042.648507302209;7810.843783124305;7886.093099293655;7891.368690504861;7896.84206315859;7911.011123609549;7866.842663146591;7912.723054693058;7936.127026027248;7937.960310198412;8010.799730006796;8319.750407487785;8646.351523635627;8968.05835387301;9065.365424420228;9071.047540008203;8656.697015604424;8791.208791208823;8818.603162857815;9235.676751313671;9363.297752668714;7518.812029699301;7616.428767842323;7625.542467451971;7625.6949722010495;7634.961825190839;8014.318782903519;8173.019237691515;8175.345972322319;8182.304504117216;8193;6360.459360954185;7666;7754.534727916343;7782;7894.171112033154;7284.307990740871;7497.10034795828;7521.3982881368265;7595.5442673439775;7623.16145224036;7411.777646670608;7425;7433.479656423999;7446.813829654302;7446.851062978819;6837.829054273683;6844.794656160124;6926.619035953071;6930.722771089302;6951.096357473496;7152.9284707152265;7230.819229519304;7231.204567497466;7234.6020968847215;7240.891386629311 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: |4;prototype.html;347.0715835140997;348.280800147695;352.55049811490346;357.3460567162093;363.1284916201117;6097.451229389354;6098.573099883004;6107.419795119467;6138.263408390993;6145.8156255312415;5787.768489260425;5850.707464626762;5938.0499120140785;6018.849528761783;6022.036474164134;4591.334256532801;4604.378408914804;4611.492735799059;4621.353010578522;4651.906961860761;4640.86077417678;4661.160991021615;4663.4637016743;4675.719456832584;4676.766161691919;4502.639788816903;4591.724496530219;4607.677462577621;4685.1566717990745;4704.341392205077;4674.813007479696;4684.273937539687;4689.109069276827;4689.695169813951;4691.62467002639;4146.502419709624;4157.10622216225;4163.208990291865;4172.728772629769;4175.895602609929;5668.376478587352;5732.770689172428;5736.168255602956;5742.310922689304;5747.482726554588;5411.594130440233;5457.372402173722;5457.617966742329;5462.726863656841;5463.617546771728;6342.270638876542;6356.332585078592;6359.618422894641;6360.777372791977;6377.872442551123;4595.241785105453;4603.378543896598;4606.60843828275;4607.14767767964;4627.051454451863;6472.093906853044;6545.280019197884;6582.93417065828;6599.3070727573395;6623.701933413008;4694.131585656664;4700.788464519036;4701.200795864711;4703.600193983481;4735.668503204743;4788.329633851264;4799.736014519235;4802.183628783116;4803.375561177025;4808.35087263222;5407.486288802557;5410.567154627621;5420.891582168413;5445.754941027663;5449.910017996416;6266.937330626726;6408.359164083648;6410.23077230726;6413.518986075969;6420.839479012969;1685.9041622945183;1690.509752171881;1706.4880535839447;1707.940222092221;1709.7606335113094;1152.9769404611861;1153.1581945180067;1165.0970497864223;1175.1949914308725;1196.216478206776;6300.905486417708;6307.148534947723;6308.27454842699;6309;6311.873762524816;6612.0412540181405;6618.238902526175;6621.9006714899315;6622.801315960529;6622.966885165543;6117.96941015292;6231.9688401557705;6237.376262373727;6353.523485738496;6366.204224471941;3567.661072198137;3569;3585.6414358564457;3594.6045934947133;3598.9100272493397;6861.4853885958755;6878.3465570770695;6888.345607167311;6891.896621550681;6896.517243792886;6660.333966603301;6818.590884546943;6833.214180369321;6834.2140653825445;6843.213030501456;6883.27725588816;6889.276625954303;6894.310568943066;6899.758508452281;6907.240203577602;6649.767258145942;6656.201255849329;6657.567258128206;6659.633720145244;6662.900056499253;6401.679916004275;6590.373914478117;6608.966955165193;6621.635810030494;6623.3376662333385;6917.619530925847;6947;6954.930450695429;6958.721651133898;6959;4547.1815073287125;4568.771561421982;4573.314002899591;4574.016586433932;4577.130345234528;6604.240512341143;6661.967395053774;6670.866582668223;6710.429613482908;6711.429528490133;6503.122078519528;6514.315996820265;6516.967415163083;6517.250516190509;6522.28254891971;6504.38208370204;6506.869862602816;6513.250976137805;6516.315786842503;6530.47756179495;6280.717367718555;6411.647359395091;6420.678966051585;6428.939225027818;6431.099646049651;6515.2833188348295;6530.314316996647;6537.8692426150255;6541.247756508063;6542.182227221598;6262.874742505215;6387.552871298964;6393.200849893763;6395.16862807832;6395.744170233139 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: |5;yui.html;857.4298091768974;882.5190271302143;951.5908159491418;951.6241084771514;957.9521023948805;13430.597082087528;13675.521356752492;13687.794683079761;13690.178589284638;13702.109362891417;13252.93373533131;13773.862261377382;13781.17312961223;13787.310634468287;13817.723645527083;733.7945375294926;737.4727070144845;738.5753191914644;902.7111324376218;907.2243232036612;901.4726385064745;903.5798353765498;905.7599736069947;905.7871400220943;905.85053466178;937.5827756648295;938.6573900526292;943.7876477792477;946.8958414574391;947.4552132523792;931.6320053578867;937.0208132501552;938.324406427378;939.1547607153548;940.05994005994;563.4140493886354;564.8277275430992;706.1702499563045;707.1019804847814;710.0946293475808;13886.4445422183;14052.789208161783;14074.507392241325;14087.788683169761;14097.71804563903;12772.233665980071;13633.318334083257;13641.795373069313;13831.861681383258;13935.930320348334;11158.888411115866;11240.606578769704;11249.887501124966;11253.887461125365;11300.265482743678;3866.1881004988986;3880.2821478026653;3910.804459777;3923.2153569286256;3951.091249012743;45928.77035614834;45977.77011114923;46583.76708116438;46957.29564056542;47024.294635580496;10506.684799456028;10576.418296993585;10599.947000264949;10773.030427261585;11078.944605277084;18470.630587388176;18630.627387452445;18631.813681863277;18634.25462981493;18676.719849202273;7048.577085374894;7052.294770523009;7279.162896266891;7390.852182956418;7397.889031664529;4504.012394724276;4516.23224051912;4551.681382303207;4568.88577785558;4606.216943119678;3132.7023932726356;3181.06158683189;3191.2181515528846;3192.1221664042573;3192.154079169022;562.8480310316191;564.6245246910759;566.2044827018046;572.7107810555643;578.308920839601;713.375796178344;714.7212587091038;717.7703134996856;727.8762610356252;747.2751431111813;8881.24509416694;8972.551372431351;9063.093690630883;9067.591958361889;9131.726048218565;709.7274408063429;731.436793668872;739.9963000185072;742.3356096293787;754.5661244784271;3875.748076375027;4010.1779135277197;4018.216447792711;4050.9797451012555;4088.550259471456;57970.71014644984;58323;58604.4139558599;58617.12074318888;58653.41346586565;51496.74251628793;51591;51739;51972.74013629908;51999.48000519946;45964.77017614891;46249.30626040612;47530.5246947533;47618.76190619094;47862;8497.320214382711;8684.826303473768;8759.080296569024;9315.580798864203;9390.906090939003;34118.31763364669;34246.14384640404;35673;35900.82049589735;36076.27847442984;43825.78087109544;44190.33714494349;44190.558094418644;45963;45975.31037034514;11299.3785341807;11780.823287650863;12044.578439754567;12142;12654.746905061664;12861.164024338388;13552.186868787905;13603.727925441237;13610.931945340606;13805.792913106512;11168.609098681507;11433;11479.139064570358;12095.939520302341;12144.14990950625;12044;12172.2696638202;12193;12203.389830508262;12493.312867791996;11806.173567850168;11842.881571184178;12047.638570842888;12087;12090.818637720617;10172.491375431347;10373.066424021874;10806.513706883052;11033.227674062739;11164.162687798611;9482.336236463381;9525.190358819578;9695.369800962915;9948.403095814274;10558.577656893945 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,875 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952673372__endTimestamp 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,382 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:31:13 INFO - 2016-02-08 09:31:13,383 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:31:13 INFO - 2016-02-08 09:31:13,383 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,383 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5593.19 Median:5805.39 stddev:630.75 (10.9%) stddev-sans-first:605.88 09:31:13 INFO - 2016-02-08 09:31:13,384 DEBUG : BROWSER_OUTPUT: Values: 3273.9 3347.8 3350.7 3366.6 3371.7 5644.5 5656.3 5670.8 5672.8 5673.5 5679.2 5687.2 5693.1 5696.8 5697.6 4930.1 5165.5 5179.8 5243.2 5261.6 5115.3 5140.6 5143.1 5148.8 5150.7 5038.2 5089.4 5142.0 5163.2 5168.1 5037.8 5119.8 5136.4 5138.2 5180.4 5136.2 5152.7 5246.0 5249.3 5281.7 5268.2 5274.7 5422.4 5455.6 5462.4 6087.4 6090.1 6094.8 6097.7 6104.3 6035.3 6106.8 6124.1 6124.8 6135.9 5414.4 5471.4 5507.6 5643.4 5675.6 6083.4 6121.4 6123.9 6124.8 6130.2 5937.5 5939.8 5940.3 5951.4 5981.3 5921.6 5984.4 6025.7 6027.2 6126.2 6040.7 6089.1 6097.1 6098.9 6104.2 6031.5 6045.7 6054.8 6100.0 6110.8 4455.8 4506.2 4573.6 4600.7 4600.8 4087.8 4094.6 4105.9 4122.5 4128.8 5518.7 5718.0 5728.5 5732.9 5734.0 5908.9 5916.3 5958.0 5984.1 6020.9 5678.9 5696.2 5700.3 5704.3 5713.1 4996.1 5109.9 5160.9 5171.1 5192.9 6129.2 6226.9 6232.4 6246.5 6248.1 6064.7 6107.9 6108.2 6114.6 6121.1 6103.1 6140.8 6142.3 6283.4 6287.9 5829.2 5847.1 5850.4 5853.0 5902.4 5900.5 5907.2 5942.8 5946.0 5957.8 5667.5 5702.8 5780.2 5805.4 5812.0 5876.3 6007.4 6021.4 6021.5 6024.6 5886.9 5904.3 5915.3 5926.6 5929.8 5773.3 5868.3 5899.2 5965.3 5971.3 5715.8 5803.0 5805.3 5819.9 5828.7 09:31:13 INFO - 2016-02-08 09:31:13,384 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,384 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51613.37 Median:3678.93 stddev:86185.15 (2342.7%) stddev-sans-first:86341.80 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: Values: 594.5 620.3 628.6 636.5 643.1 32878.5 41412.8 41435.8 41495.6 41651.4 45799.8 46465.0 46470.3 46534.0 46538.3 8057.9 8158.1 8162.6 8960.2 9119.1 10778.2 10923.7 11014.6 11023.9 11033.8 10626.0 10737.7 10739.4 10743.9 10745.4 10643.4 10747.0 10765.0 10767.7 10772.7 10420.5 10620.1 10630.9 10634.4 10638.6 42645.0 43059.8 43128.6 43159.8 43165.6 3721.7 3764.3 3875.6 3958.9 4186.3 32361.0 32664.3 32881.7 34034.7 34051.7 3654.5 3703.4 3766.7 3778.5 3816.9 271843.0 275185.0 276229.0 276641.0 277429.0 63152.4 64801.0 64929.0 73548.0 75353.2 64797.7 64866.0 65120.3 65165.7 65261.7 16797.8 16948.0 16969.9 16975.8 17008.0 12842.6 12938.3 12938.7 13014.5 13059.0 21973.3 22092.8 22099.4 22173.3 22200.2 8351.7 8583.9 8610.3 8695.6 8742.4 1026.3 1056.8 1090.1 1140.2 1166.3 28762.6 28945.0 29094.3 29701.0 29983.4 1152.2 1171.5 1188.2 1224.0 1266.6 14863.6 15554.8 15831.9 15896.2 17233.8 403242.0 408233.0 408592.0 408794.0 408873.0 58348.0 58921.4 59000.7 59012.4 59063.1 1342.8 1499.8 1500.5 1502.9 1519.1 31186.0 31638.4 32054.2 32225.4 32506.7 49756.0 50504.5 50595.7 50627.2 50651.5 281894.0 286856.0 289196.0 289820.0 291353.0 34857.3 47753.5 48148.3 48165.0 48201.3 48043.0 48936.0 48950.5 49394.5 49466.8 40346.4 41610.4 41613.4 41754.4 42198.0 41365.4 42153.4 42171.8 44590.6 45680.3 38175.0 38193.4 38387.2 38602.2 38841.8 33974.5 34438.3 34603.8 40120.4 40850.8 26443.0 26596.0 26626.6 26693.9 26815.2 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31474.95 Median:1883.47 stddev:99703.97 (5293.6%) stddev-sans-first:99956.32 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: Values: 379.7 382.8 385.1 392.8 396.3 14864.3 16331.9 17725.2 19223.8 19246.8 11494.8 11749.9 11766.9 18529.2 19005.8 5130.1 5257.2 5261.7 5264.5 5265.6 4145.3 4250.1 4263.6 4292.3 5194.9 5493.1 5650.2 5658.2 5664.7 5695.2 5507.9 5684.2 5690.3 5690.7 5690.8 5471.8 5495.2 5503.2 5511.0 5512.1 13775.8 13863.9 14054.5 14128.8 14167.3 2321.4 2418.7 2467.7 2492.1 2538.1 10591.6 10611.3 10612.2 10737.2 11510.4 1802.0 1883.2 1977.2 1980.8 2021.4 65253.0 66094.0 66506.0 67459.3 67678.7 10847.5 11146.5 11237.4 11276.8 11325.1 14320.8 14811.0 14811.3 14850.8 14851.2 7854.8 7886.6 7928.5 8133.5 8210.8 7492.3 7554.0 7965.9 8030.4 8117.0 4584.4 4681.6 4710.6 4830.3 4871.3 1809.7 1836.4 1883.7 1909.5 1947.2 894.2 986.7 1062.9 1080.7 1082.1 14703.0 17602.2 17738.3 18176.9 18324.4 1033.2 1037.7 1049.1 1065.8 1093.5 9911.0 10053.7 10070.4 10083.2 10085.5 597748.0 599102.0 607534.0 621108.0 625464.0 81546.7 82234.2 83615.0 84185.0 84445.0 1193.1 1196.7 1196.9 1197.9 1209.1 16160.0 16288.3 16295.7 16398.2 16600.2 36767.3 37749.0 38135.4 38406.6 38641.6 49370.3 49684.5 50043.7 53305.1 53466.0 10662.3 10781.2 10859.8 10884.6 10886.2 18233.0 18671.1 18752.7 18816.0 18891.2 14198.4 15484.5 15779.6 15843.8 16082.2 15314.3 15426.8 15498.3 15735.9 16183.6 11515.4 15501.9 15674.0 15741.0 16164.4 9929.3 10116.8 10249.4 10252.9 10288.5 14784.3 14874.7 14997.3 15001.8 15208.8 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,385 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5814.41 Median:5034.06 stddev:2988.44 (59.4%) stddev-sans-first:2980.02 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: Values: 1598.4 1600.2 1859.8 1863.8 1871.9 8032.3 8422.2 8428.7 8429.2 8431.7 7256.4 7466.1 7570.3 7745.1 8237.1 2313.4 2374.9 2399.0 2404.7 2408.6 2170.2 2409.8 2410.7 2455.1 2460.4 2146.6 2375.2 2420.2 2423.9 2424.6 2315.0 2357.6 2363.8 2367.2 2368.1 2348.8 2359.0 2385.9 2388.0 2393.6 8156.4 8231.4 8236.3 8237.5 8241.5 4907.2 5031.9 5033.6 5034.5 5035.8 3191.8 3207.2 3242.0 3260.6 3263.2 1056.5 1063.9 1075.9 1886.0 1889.6 10971.5 11112.2 11131.3 11135.3 11148.0 4259.7 4322.5 4388.5 4518.4 4525.1 9355.8 9371.8 9375.8 9376.6 9376.6 4980.4 4994.6 5004.7 5011.4 5015.8 4579.1 4582.2 4623.1 4626.6 4629.5 3682.2 3705.5 3715.8 3738.4 3763.9 1095.9 1098.5 1134.1 1134.3 1138.5 1128.3 1178.5 1192.9 1200.6 1212.4 7783.7 7843.6 7847.1 7856.2 8057.5 1229.0 1233.2 1234.3 1237.7 1241.1 6251.8 6371.7 6424.9 6431.9 6441.2 9773.0 10004.2 10108.2 10117.1 10131.4 9555.1 10001.2 10006.8 10012.1 10042.6 7810.8 7886.1 7891.4 7896.8 7911.0 7866.8 7912.7 7936.1 7938.0 8010.8 8319.8 8646.4 8968.1 9065.4 9071.0 8656.7 8791.2 8818.6 9235.7 9363.3 7518.8 7616.4 7625.5 7625.7 7635.0 8014.3 8173.0 8175.3 8182.3 8193.0 6360.5 7666.0 7754.5 7782.0 7894.2 7284.3 7497.1 7521.4 7595.5 7623.2 7411.8 7425.0 7433.5 7446.8 7446.9 6837.8 6844.8 6926.6 6930.7 6951.1 7152.9 7230.8 7231.2 7234.6 7240.9 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5420.05 Median:6188.89 stddev:1608.31 (26.0%) stddev-sans-first:1567.11 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: Values: 347.1 348.3 352.6 357.3 363.1 6097.5 6098.6 6107.4 6138.3 6145.8 5787.8 5850.7 5938.0 6018.8 6022.0 4591.3 4604.4 4611.5 4621.4 4651.9 4640.9 4661.2 4663.5 4675.7 4676.8 4502.6 4591.7 4607.7 4685.2 4704.3 4674.8 4684.3 4689.1 4689.7 4691.6 4146.5 4157.1 4163.2 4172.7 4175.9 5668.4 5732.8 5736.2 5742.3 5747.5 5411.6 5457.4 5457.6 5462.7 5463.6 6342.3 6356.3 6359.6 6360.8 6377.9 4595.2 4603.4 4606.6 4607.1 4627.1 6472.1 6545.3 6582.9 6599.3 6623.7 4694.1 4700.8 4701.2 4703.6 4735.7 4788.3 4799.7 4802.2 4803.4 4808.4 5407.5 5410.6 5420.9 5445.8 5449.9 6266.9 6408.4 6410.2 6413.5 6420.8 1685.9 1690.5 1706.5 1707.9 1709.8 1153.0 1153.2 1165.1 1175.2 1196.2 6300.9 6307.1 6308.3 6309.0 6311.9 6612.0 6618.2 6621.9 6622.8 6623.0 6118.0 6232.0 6237.4 6353.5 6366.2 3567.7 3569.0 3585.6 3594.6 3598.9 6861.5 6878.3 6888.3 6891.9 6896.5 6660.3 6818.6 6833.2 6834.2 6843.2 6883.3 6889.3 6894.3 6899.8 6907.2 6649.8 6656.2 6657.6 6659.6 6662.9 6401.7 6590.4 6609.0 6621.6 6623.3 6917.6 6947.0 6954.9 6958.7 6959.0 4547.2 4568.8 4573.3 4574.0 4577.1 6604.2 6662.0 6670.9 6710.4 6711.4 6503.1 6514.3 6517.0 6517.3 6522.3 6504.4 6506.9 6513.3 6516.3 6530.5 6280.7 6411.6 6420.7 6428.9 6431.1 6515.3 6530.3 6537.9 6541.2 6542.2 6262.9 6387.6 6393.2 6395.2 6395.7 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14142.89 Median:4533.96 stddev:15951.55 (351.8%) stddev-sans-first:15965.09 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: Values: 857.4 882.5 951.6 951.6 958.0 13430.6 13675.5 13687.8 13690.2 13702.1 13252.9 13773.9 13781.2 13787.3 13817.7 733.8 737.5 738.6 902.7 907.2 901.5 903.6 905.8 905.8 905.9 937.6 938.7 943.8 946.9 947.5 931.6 937.0 938.3 939.2 940.1 563.4 564.8 706.2 707.1 710.1 13886.4 14052.8 14074.5 14087.8 14097.7 12772.2 13633.3 13641.8 13831.9 13935.9 11158.9 11240.6 11249.9 11253.9 11300.3 3866.2 3880.3 3910.8 3923.2 3951.1 45928.8 45977.8 46583.8 46957.3 47024.3 10506.7 10576.4 10599.9 10773.0 11078.9 18470.6 18630.6 18631.8 18634.3 18676.7 7048.6 7052.3 7279.2 7390.9 7397.9 4504.0 4516.2 4551.7 4568.9 4606.2 3132.7 3181.1 3191.2 3192.1 3192.2 562.8 564.6 566.2 572.7 578.3 713.4 714.7 717.8 727.9 747.3 8881.2 8972.6 9063.1 9067.6 9131.7 709.7 731.4 740.0 742.3 754.6 3875.7 4010.2 4018.2 4051.0 4088.6 57970.7 58323.0 58604.4 58617.1 58653.4 51496.7 51591.0 51739.0 51972.7 51999.5 45964.8 46249.3 47530.5 47618.8 47862.0 8497.3 8684.8 8759.1 9315.6 9390.9 34118.3 34246.1 35673.0 35900.8 36076.3 43825.8 44190.3 44190.6 45963.0 45975.3 11299.4 11780.8 12044.6 12142.0 12654.7 12861.2 13552.2 13603.7 13610.9 13805.8 11168.6 11433.0 11479.1 12095.9 12144.1 12044.0 12172.3 12193.0 12203.4 12493.3 11806.2 11842.9 12047.6 12087.0 12090.8 10172.5 10373.1 10806.5 11033.2 11164.2 9482.3 9525.2 9695.4 9948.4 10558.6 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:31:13 INFO - 2016-02-08 09:31:13,387 DEBUG : BROWSER_OUTPUT: 09:31:13 INFO - 2016-02-08 09:31:13,976 INFO : Browser exited with error code: 0 09:31:13 INFO - 2016-02-08 09:31:13,999 INFO : Completed test dromaeo_css (00:18:29) 09:31:13 INFO - 2016-02-08 09:31:13,999 INFO : Starting test kraken 09:31:13 INFO - 2016-02-08 09:31:13,999 DEBUG : operating with platform_type : linux_ 09:31:13 INFO - 2016-02-08 09:31:13,999 INFO : Initialising browser for kraken test... 09:31:14 INFO - 2016-02-08 09:31:14,005 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3nY0BJ/profile http://localhost:56557/getInfo.html 09:31:17 INFO - 2016-02-08 09:31:17,832 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:31:17 INFO - 2016-02-08 09:31:17,832 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:31:17 INFO - 2016-02-08 09:31:17,832 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:31:17 INFO - 2016-02-08 09:31:17,832 DEBUG : BROWSER_OUTPUT: __metrics 09:31:17 INFO - 2016-02-08 09:31:17,989 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:18 INFO - 2016-02-08 09:31:18,675 INFO : Browser initialized. 09:31:18 INFO - 2016-02-08 09:31:18,676 INFO : Running cycle 1/1 for kraken test... 09:31:18 INFO - 2016-02-08 09:31:18,676 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3nY0BJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:31:30 INFO - 2016-02-08 09:31:30,399 DEBUG : BROWSER_OUTPUT: RSS: Main: 134914048 09:31:30 INFO - 2016-02-08 09:31:30,400 DEBUG : BROWSER_OUTPUT: 09:31:32 INFO - 2016-02-08 09:31:32,822 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56557/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:33 INFO - 2016-02-08 09:31:33,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 168005632 09:31:33 INFO - 2016-02-08 09:31:33,395 DEBUG : BROWSER_OUTPUT: 09:31:35 INFO - 2016-02-08 09:31:35,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56557/tests/kraken/driver.html?testName=audio-dft) 09:31:36 INFO - 2016-02-08 09:31:36,526 DEBUG : BROWSER_OUTPUT: RSS: Main: 166596608 09:31:36 INFO - 2016-02-08 09:31:36,527 DEBUG : BROWSER_OUTPUT: 09:31:39 INFO - 2016-02-08 09:31:39,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56557/tests/kraken/driver.html?testName=audio-fft) 09:31:39 INFO - 2016-02-08 09:31:39,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 163115008 09:31:39 INFO - 2016-02-08 09:31:39,878 DEBUG : BROWSER_OUTPUT: 09:31:41 INFO - 2016-02-08 09:31:41,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56557/tests/kraken/driver.html?testName=audio-oscillator) 09:31:42 INFO - 2016-02-08 09:31:42,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 163188736 09:31:42 INFO - 2016-02-08 09:31:42,424 DEBUG : BROWSER_OUTPUT: 09:31:44 INFO - 2016-02-08 09:31:44,597 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56557/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:31:45 INFO - 2016-02-08 09:31:45,151 DEBUG : BROWSER_OUTPUT: RSS: Main: 160825344 09:31:45 INFO - 2016-02-08 09:31:45,151 DEBUG : BROWSER_OUTPUT: 09:31:47 INFO - 2016-02-08 09:31:47,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56557/tests/kraken/driver.html?testName=imaging-darkroom) 09:31:48 INFO - 2016-02-08 09:31:48,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 160034816 09:31:48 INFO - 2016-02-08 09:31:48,557 DEBUG : BROWSER_OUTPUT: 09:31:51 INFO - 2016-02-08 09:31:51,883 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56557/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:52 INFO - 2016-02-08 09:31:52,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 161599488 09:31:52 INFO - 2016-02-08 09:31:52,457 DEBUG : BROWSER_OUTPUT: 09:31:55 INFO - 2016-02-08 09:31:55,119 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56557/tests/kraken/driver.html?testName=json-parse-financial) 09:31:55 INFO - 2016-02-08 09:31:55,688 DEBUG : BROWSER_OUTPUT: RSS: Main: 162213888 09:31:55 INFO - 2016-02-08 09:31:55,688 DEBUG : BROWSER_OUTPUT: 09:31:57 INFO - 2016-02-08 09:31:57,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56557/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:57 INFO - 2016-02-08 09:31:57,790 DEBUG : BROWSER_OUTPUT: RSS: Main: 169029632 09:31:57 INFO - 2016-02-08 09:31:57,791 DEBUG : BROWSER_OUTPUT: 09:31:59 INFO - 2016-02-08 09:31:59,540 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:00 INFO - 2016-02-08 09:32:00,143 DEBUG : BROWSER_OUTPUT: RSS: Main: 168497152 09:32:00 INFO - 2016-02-08 09:32:00,143 DEBUG : BROWSER_OUTPUT: 09:32:02 INFO - 2016-02-08 09:32:02,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:02 INFO - 2016-02-08 09:32:02,662 DEBUG : BROWSER_OUTPUT: RSS: Main: 173920256 09:32:02 INFO - 2016-02-08 09:32:02,662 DEBUG : BROWSER_OUTPUT: 09:32:05 INFO - 2016-02-08 09:32:05,018 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:05 INFO - 2016-02-08 09:32:05,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 177008640 09:32:05 INFO - 2016-02-08 09:32:05,600 DEBUG : BROWSER_OUTPUT: 09:32:08 INFO - 2016-02-08 09:32:08,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:08 INFO - 2016-02-08 09:32:08,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 174546944 09:32:08 INFO - 2016-02-08 09:32:08,625 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56557/tests/kraken/driver.html?testName=ai-astar) 09:32:10 INFO - 2016-02-08 09:32:10,512 DEBUG : BROWSER_OUTPUT: RSS: Main: 233480192 09:32:10 INFO - 2016-02-08 09:32:10,512 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,512 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:32:10 INFO - 2016-02-08 09:32:10,512 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:32:10 INFO - 2016-02-08 09:32:10,513 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:32:10 INFO - 2016-02-08 09:32:10,513 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:32:10 INFO - 2016-02-08 09:32:10,513 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;104;109;109;108;98;107;108;107 09:32:10 INFO - 2016-02-08 09:32:10,513 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;122;145;157;130;146;159;122;159;122 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;159;161;161;161;160;162;160;161 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;74;145;74;72;73;74;149;72 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;138;91;92;93;138;91;92;134 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;108;109;109;109;109;109;110;109;109 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;147;145;145;145;146;145;145;145;144 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;95;95;94;95;95;96;96 09:32:10 INFO - 2016-02-08 09:32:10,514 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;58;56;57;58;57;59;57;59;55 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;63;63;63;64;63;63;63;63;63 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;77;77;77;78;77;78;79;79 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;117;112;124;110;120;118;115;115;116 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;143;155;141;143;134;135;141;141;132 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;62;62;63;63;63;64;64;62 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:32:10 INFO - 2016-02-08 09:32:10,515 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:32:10 INFO - 2016-02-08 09:32:10,516 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,904 09:32:10 INFO - 2016-02-08 09:32:10,516 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:32:10 INFO - 2016-02-08 09:32:10,516 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952730511__endTimestamp 09:32:10 INFO - 2016-02-08 09:32:10,516 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,519 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:32:10 INFO - 2016-02-08 09:32:10,520 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:32:10 INFO - 2016-02-08 09:32:10,520 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,520 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.70 Median:108.50 stddev:3.47 (3.2%) stddev-sans-first:3.67 09:32:10 INFO - 2016-02-08 09:32:10,520 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 104.0 109.0 109.0 108.0 98.0 107.0 108.0 107.0 09:32:10 INFO - 2016-02-08 09:32:10,520 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,521 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.30 Median:148.50 stddev:15.80 (10.6%) stddev-sans-first:16.37 09:32:10 INFO - 2016-02-08 09:32:10,521 DEBUG : BROWSER_OUTPUT: Values: 151.0 122.0 145.0 157.0 130.0 146.0 159.0 122.0 159.0 122.0 09:32:10 INFO - 2016-02-08 09:32:10,521 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,521 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 09:32:10 INFO - 2016-02-08 09:32:10,521 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 159.0 161.0 161.0 161.0 160.0 162.0 160.0 161.0 09:32:10 INFO - 2016-02-08 09:32:10,522 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,522 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:72.50 stddev:31.34 (43.2%) stddev-sans-first:32.73 09:32:10 INFO - 2016-02-08 09:32:10,522 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 74.0 145.0 74.0 72.0 73.0 74.0 149.0 72.0 09:32:10 INFO - 2016-02-08 09:32:10,522 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,522 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.40 Median:92.00 stddev:21.61 (23.5%) stddev-sans-first:22.37 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 138.0 91.0 92.0 93.0 138.0 91.0 92.0 134.0 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: Values: 109.0 108.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,523 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: Values: 145.0 147.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 144.0 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 95.0 95.0 94.0 95.0 95.0 96.0 96.0 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:1.45 (2.5%) stddev-sans-first:1.32 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: Values: 55.0 58.0 56.0 57.0 58.0 57.0 59.0 57.0 59.0 55.0 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 63.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 09:32:10 INFO - 2016-02-08 09:32:10,524 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:0.82 (1.1%) stddev-sans-first:0.83 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 77.0 77.0 77.0 78.0 77.0 78.0 79.0 79.0 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:116.50 stddev:3.98 (3.4%) stddev-sans-first:4.15 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: Values: 114.0 117.0 112.0 124.0 110.0 120.0 118.0 115.0 115.0 116.0 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.50 Median:141.00 stddev:6.40 (4.5%) stddev-sans-first:6.78 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: Values: 140.0 143.0 155.0 141.0 143.0 134.0 135.0 141.0 141.0 132.0 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 09:32:10 INFO - 2016-02-08 09:32:10,525 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 62.0 62.0 63.0 63.0 63.0 64.0 64.0 62.0 09:32:10 INFO - 2016-02-08 09:32:10,526 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:32:10 INFO - 2016-02-08 09:32:10,526 DEBUG : BROWSER_OUTPUT: 09:32:11 INFO - 2016-02-08 09:32:11,252 INFO : Browser exited with error code: 0 09:32:11 INFO - 2016-02-08 09:32:11,305 INFO : Completed test kraken (00:00:57) 09:32:11 INFO - 2016-02-08 09:32:11,305 INFO : Starting test v8_7 09:32:11 INFO - 2016-02-08 09:32:11,305 DEBUG : operating with platform_type : linux_ 09:32:11 INFO - 2016-02-08 09:32:11,306 INFO : Initialising browser for v8_7 test... 09:32:11 INFO - 2016-02-08 09:32:11,312 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgW9l7M/profile http://localhost:56557/getInfo.html 09:32:15 INFO - 2016-02-08 09:32:15,078 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:32:15 INFO - 2016-02-08 09:32:15,078 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:32:15 INFO - 2016-02-08 09:32:15,078 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:32:15 INFO - 2016-02-08 09:32:15,078 DEBUG : BROWSER_OUTPUT: __metrics 09:32:15 INFO - 2016-02-08 09:32:15,235 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:32:15 INFO - 2016-02-08 09:32:15,882 INFO : Browser initialized. 09:32:15 INFO - 2016-02-08 09:32:15,882 INFO : Running cycle 1/1 for v8_7 test... 09:32:15 INFO - 2016-02-08 09:32:15,883 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgW9l7M/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:32:27 INFO - 2016-02-08 09:32:27,669 DEBUG : BROWSER_OUTPUT: RSS: Main: 134856704 09:32:27 INFO - 2016-02-08 09:32:27,669 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,018 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56557/tests/v8_7/run.html (next: http://localhost:56557/tests/v8_7/run.html) 09:32:49 INFO - 2016-02-08 09:32:49,268 DEBUG : BROWSER_OUTPUT: RSS: Main: 431001600 09:32:49 INFO - 2016-02-08 09:32:49,269 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,270 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:32:49 INFO - 2016-02-08 09:32:49,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:32:49 INFO - 2016-02-08 09:32:49,270 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:32:49 INFO - 2016-02-08 09:32:49,270 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:32:49 INFO - 2016-02-08 09:32:49,270 DEBUG : BROWSER_OUTPUT: |0;Richards;164.20361247947454 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.15276630883568 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.2415136338342 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |4;RayTrace;958.7727708533077 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |5;Earley;542.8881650380022 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |6;Boyer;10562.5 09:32:49 INFO - 2016-02-08 09:32:49,271 DEBUG : BROWSER_OUTPUT: |7;RegExp;24609.756097560974 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: |8;Splay;517.4537987679671 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: __startTimestamp1454952769269__endTimestamp 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.20 Median:164.20 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,272 DEBUG : BROWSER_OUTPUT: Values: 164.2 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.15 Median:165.15 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: Values: 165.2 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.24 Median:278.24 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: Values: 278.2 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: Values: 5138.5 09:32:49 INFO - 2016-02-08 09:32:49,273 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,274 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:958.77 Median:958.77 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: Values: 958.8 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.89 Median:542.89 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: Values: 542.9 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,275 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10562.50 Median:10562.50 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: Values: 10562.5 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24609.76 Median:24609.76 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: Values: 24609.8 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:517.45 Median:517.45 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: Values: 517.5 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: Values: 4835.7 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:32:49 INFO - 2016-02-08 09:32:49,276 DEBUG : BROWSER_OUTPUT: 09:32:49 INFO - 2016-02-08 09:32:49,988 INFO : Browser exited with error code: 0 09:32:50 INFO - 2016-02-08 09:32:50,004 INFO : Completed test v8_7 (00:00:38) 09:32:50 INFO - 2016-02-08 09:32:50,324 INFO : Completed test suite (00:20:06) 09:32:50 INFO - 2016-02-08 09:32:50,324 DEBUG : Working with test: dromaeo_css 09:32:50 INFO - 2016-02-08 09:32:50,325 DEBUG : Generating results file: dromaeo_css 09:32:50 INFO - 2016-02-08 09:32:50,326 DEBUG : Working with test: kraken 09:32:50 INFO - 2016-02-08 09:32:50,326 DEBUG : Generating results file: kraken 09:32:50 INFO - 2016-02-08 09:32:50,326 INFO : javascript benchmark 09:32:50 INFO - 2016-02-08 09:32:50,326 DEBUG : Working with test: v8_7 09:32:50 INFO - 2016-02-08 09:32:50,326 DEBUG : Generating results file: v8_7 09:32:50 INFO - 2016-02-08 09:32:50,327 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:32:50 INFO - 2016-02-08 09:32:50,379 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:32:50 INFO - 2016-02-08 09:32:50,379 DEBUG : process_Request line: dromaeo_css 7412.84 graph.html#tests=[[72,53,35]] 09:32:50 INFO - 2016-02-08 09:32:50,379 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:32:50 INFO - 2016-02-08 09:32:50,416 DEBUG : process_Request line: kraken 1468.90 graph.html#tests=[[232,53,35]] 09:32:50 INFO - 2016-02-08 09:32:50,417 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:32:50 INFO - 2016-02-08 09:32:50,448 DEBUG : process_Request line: v8_7 23312.54 graph.html#tests=[[230,53,35]] 09:32:50 INFO - 2016-02-08 09:32:50,449 INFO : javascript benchmark 09:32:50 INFO - 2016-02-08 09:32:50,453 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3273.852676629552, 3347.7656564040512, 3350.698437140657, 3366.562346894904, 3371.6628337166303, 5644.463775941289, 5656.321241451026, 5670.773169073237, 5672.773089076437, 5673.546116310696, 5679.204911312409, 5687.17535957286, 5693.117566777146, 5696.772129114841, 5697.6011679182475, 4930.137225985456, 5165.483451654842, 5179.792808287682, 5243.187305967562, 5261.605379596526, 5115.283860259567, 5140.588752899759, 5143.125668636322, 5148.7940482380845, 5150.7424628768595, 5038.2190760432, 5089.389273287211, 5141.974290128563, 5163.173892177252, 5168.095583272931, 5037.848864534069, 5119.820806271762, 5136.43499215086, 5138.229265610151, 5180.4301526832205, 5136.203888397305, 5152.716600586966, 5246.0, 5249.291345668324, 5281.656692314988, 5268.236105764681, 5274.736263186864, 5422.40353561108, 5455.618106732531, 5462.371827239878, 6087.421694939018, 6090.120626059225, 6094.756209751605, 6097.7368455077885, 6104.328523862371, 6035.3059398169335, 6106.755729770804, 6124.11200375943, 6124.816255512342, 6135.9079613805825, 5414.431484694129, 5471.425500322409, 5507.614466987313, 5643.351014633328, 5675.602707810455, 6083.393013447916, 6121.418465245794, 6123.877522449526, 6124.846878828065, 6130.172426722335, 5937.524998000236, 5939.762409503658, 5940.257467816523, 5951.404859514014, 5981.252343457068, 5921.61509501881, 5984.371640977634, 6025.728842202024, 6027.216461860018, 6126.23422072241, 6040.6677632730625, 6089.147519347295, 6097.115918191837, 6098.878022439526, 6104.2064531611495, 6031.517478601701, 6045.667488288186, 6054.788082417094, 6099.969500152471, 6110.7861224856915, 4455.844045458458, 4506.1663592235545, 4573.565511276423, 4600.700954437951, 4600.838970636011, 4087.8160482777753, 4094.590540945941, 4105.91788164235, 4122.546519882871, 4128.772917489567, 5518.668879867221, 5717.999350113797, 5728.455796699307, 5732.942670573324, 5734.025215713339, 5908.940910590839, 5916.349201587822, 5957.970210148921, 5984.132300816357, 6020.879582408327, 5678.943210567842, 5696.202531645573, 5700.315962084494, 5704.258446402024, 5713.057345538023, 4996.125678006407, 5109.897802044013, 5160.948390516047, 5171.095058364846, 5192.870178245574, 6129.246503173489, 6226.875462490815, 6232.439080482688, 6246.469050130699, 6248.125262463259, 6064.696765161636, 6107.877842443215, 6108.17539632144, 6114.57197996136, 6121.112438696453, 6103.115048318058, 6140.824239517171, 6142.283918229586, 6283.403076707706, 6287.937120628735, 5829.213056237354, 5847.06446968483, 5850.444207800252, 5853.0, 5902.350741418355, 5900.498457631149, 5907.232059832194, 5942.821715348547, 5946.0, 5957.76168953237, 5667.518260947865, 5702.7718891243885, 5780.248567686174, 5805.390434004369, 5811.970940145272, 5876.265466816648, 6007.3992600739575, 6021.397860213943, 6021.54838387114, 6024.608400454132, 5886.911696324644, 5904.291485021825, 5915.260592425947, 5926.614770039933, 5929.792457263975, 5773.3072031356505, 5868.295804503487, 5899.17411562373, 5965.254343207099, 5971.28344598651, 5715.799947002001, 5802.970985145047, 5805.332386775406, 5819.854503637443, 5828.679422631796], "unit": "ms", "name": "dojo.html", "value": 5551.441721305893}, {"lowerIsBetter": false, "replicates": [594.4738906068128, 620.3207487800856, 628.5568674084769, 636.522608043967, 643.1285608001158, 32878.506822397656, 41412.79293603528, 41435.79282103593, 41495.58504414955, 41651.37522937158, 45799.771001144945, 46465.0, 46470.30294545584, 46534.0, 46538.30192547106, 8057.879131813028, 8158.143394943534, 8162.591870406456, 8960.193582577567, 9119.088091190893, 10778.19163562732, 10923.672289831278, 11014.559417623286, 11023.889761102406, 11033.83449248262, 10626.0, 10737.731556711067, 10739.355638661666, 10743.892561074406, 10745.355278683264, 10643.361398316087, 10747.032767051003, 10764.977327153947, 10767.730806729816, 10772.676819695422, 10420.478976051167, 10620.097291730212, 10630.946845265724, 10634.415107169103, 10638.62764803236, 42645.0, 43059.78470107629, 43128.568714313085, 43159.78420107879, 43165.56834431647, 3721.7394782365245, 3764.284785890672, 3875.61243875613, 3958.920821583581, 4186.267403204427, 32361.0, 32664.346713065843, 32881.6711832881, 34034.659653403396, 34051.6594834051, 3654.476783821582, 3703.3889408247605, 3766.6610005099674, 3778.471014058034, 3816.866409675648, 271843.0, 275185.0, 276229.0, 276640.99999999796, 277429.0, 63152.36847631465, 64801.0, 64929.0, 73548.0, 75353.24646753463, 64797.676011620584, 64866.0, 65120.34879651238, 65165.67417162884, 65261.67369163124, 16797.83202167987, 16948.0, 16969.915150424167, 16975.830241697673, 17008.0, 12842.550510732079, 12938.288394138224, 12938.741225175443, 13014.54449094296, 13059.0, 21973.340799775713, 22092.779072209392, 22099.447513812283, 22173.33479995603, 22200.222992195195, 8351.749447516584, 8583.914160858436, 8610.268127209258, 8695.608697608623, 8742.38803283764, 1026.3174988632625, 1056.7675111475462, 1090.1224514266091, 1140.236041851961, 1166.3060479014973, 28762.568561471595, 28945.0, 29094.272643183667, 29701.0, 29983.400331993238, 1152.2049785647876, 1171.5372427891107, 1188.1742189178437, 1224.0452447091284, 1266.6200139957975, 14863.628409289855, 15554.766678499831, 15831.920840395724, 15896.205189740467, 17233.827661723473, 403242.0, 408233.0, 408592.0, 408794.0, 408873.0, 58348.0, 58921.41078589245, 59000.70499647474, 59012.40987590155, 59063.11405328924, 1342.7583035053783, 1499.8350181480027, 1500.534834201401, 1502.9098254104788, 1519.050593379138, 31186.0, 31638.367232654757, 32054.19864503406, 32225.35549289048, 32506.674933250364, 49756.0, 50504.49495504998, 50595.74702126466, 50627.22804701818, 50651.493485064675, 281894.0, 286856.0, 289196.0, 289820.0, 291353.0, 34857.302853942274, 47753.522464774906, 48148.27777583409, 48165.0, 48201.276980846014, 48043.0, 48936.0, 48950.510494894595, 49394.50605493899, 49466.75266623644, 40346.39480407855, 41610.37584436296, 41613.37579936243, 41754.37368439537, 42198.0, 41365.37951930783, 42153.36769948392, 42171.7891410541, 44590.55409445864, 45680.31479527743, 38175.04562385852, 38193.42709859299, 38387.232255355295, 38602.227955440176, 38841.805790970866, 33974.49038264379, 34438.31123377568, 34603.826980864935, 40120.39819402653, 40850.79574602108, 26443.0, 26596.0, 26626.600600990612, 26693.866530667998, 26815.19554413371], "unit": "ms", "name": "ext.html", "value": 20631.487331649736}, {"lowerIsBetter": false, "replicates": [379.74936541882363, 382.84877473398024, 385.12192201779953, 392.8075243130865, 396.2688839091876, 14864.33110510027, 16331.91834040831, 17725.202365893532, 19223.807761922377, 19246.807531924693, 11494.770104597903, 11749.941250293758, 11766.882331176686, 18529.16618752156, 19005.809941900578, 5130.076586214489, 5257.158854583267, 5261.710605916673, 5264.499872512124, 5265.605079619044, 4145.316022856225, 4250.128723611668, 4263.637590804769, 4292.29177185764, 5194.948050519484, 5493.121100623901, 5650.208970744099, 5658.151277308416, 5664.688442135681, 5695.202671625955, 5507.862303442426, 5684.2326285951285, 5690.260266165393, 5690.658560486384, 5690.829275121753, 5471.781128754845, 5495.230667706524, 5503.2020357048405, 5511.035568775448, 5512.090505066659, 13775.793363099561, 13863.930680346533, 14054.508092216825, 14128.788068178985, 14167.291635418187, 2321.442853715113, 2418.6734790803193, 2467.679201703785, 2492.0779311654624, 2538.1497198438547, 10591.649121759145, 10611.257211995164, 10612.20408469368, 10737.24839261252, 11510.366929818856, 1801.9549511262323, 1883.2372888979849, 1977.1893523655233, 1980.8118228768244, 2021.353166986565, 65253.0, 66094.0, 66505.99999999952, 67459.32540674579, 67678.66160669214, 10847.51186196623, 11146.498407571678, 11237.438128093563, 11276.830847537294, 11325.093992480583, 14320.785188222186, 14811.0, 14811.25943702832, 14850.777238341434, 14851.183184924717, 7854.8429031419055, 7886.605669716606, 7928.524288542706, 8133.471324363897, 8210.794730131794, 7492.250774922573, 7553.962230188814, 7965.8805117923275, 8030.437869349206, 8117.0, 4584.426946631671, 4681.578657920803, 4710.646701497401, 4830.2513110467935, 4871.269309603517, 1809.7285407188763, 1836.40316897009, 1883.6986082226779, 1909.455805095541, 1947.1960377056992, 894.1684233662756, 986.7385142936984, 1062.877769056553, 1080.7190130565953, 1082.0856376361962, 14703.0, 17602.207900644502, 17738.29046838138, 18176.90911545434, 18324.358647447276, 1033.245730616654, 1037.699067270483, 1049.0558497352292, 1065.7868426314767, 1093.5297821936645, 9910.950445247727, 10053.69838904834, 10070.395776253301, 10083.19334453242, 10085.49572521371, 597748.0, 599102.0, 607534.0, 621108.0, 625464.0, 81546.7381304753, 82234.17765822385, 83615.0, 84185.0, 84445.0, 1193.0634451955173, 1196.7127889306678, 1196.8803119687961, 1197.8682344942224, 1209.0810983652311, 16160.0, 16288.3484660617, 16295.67408651844, 16398.18009099564, 16600.169991500134, 36767.264654707295, 37749.0, 38135.42796857994, 38406.61593384142, 38641.647542335886, 49370.259446107615, 49684.503154967984, 50043.74978125086, 53305.1343202986, 53466.0, 10662.253642244968, 10781.24531282834, 10859.782804343711, 10884.61903833362, 10886.183536234656, 18233.0, 18671.06644667734, 18752.718709219098, 18816.0, 18891.244350225836, 14198.3610737515, 15484.458043968407, 15779.605509861885, 15843.841561584237, 16082.195890205678, 15314.310856011283, 15426.84573154299, 15498.302576383865, 15735.921320393325, 16183.595410114842, 11515.424228788695, 15501.922490387477, 15674.0, 15741.0, 16164.353425862833, 9929.255305852235, 10116.797664046826, 10249.436281004617, 10252.897471025195, 10288.537015834156, 14784.260786960394, 14874.702505949605, 14997.325120369827, 15001.849981500482, 15208.847911521187], "unit": "ms", "name": "jquery.html", "value": 9289.499778191532}, {"lowerIsBetter": false, "replicates": [1598.3686443854679, 1600.2078970909402, 1859.8140185981401, 1863.79498255192, 1871.8955816068521, 8032.317253033492, 8422.199891010352, 8428.662853485868, 8429.19922607352, 8431.662733490653, 7256.419486441086, 7466.104067511893, 7570.280823321776, 7745.109312429072, 8237.052738935025, 2313.3985163857433, 2374.905003799846, 2399.028393500635, 2404.699412573428, 2408.5544174327806, 2170.1861801824316, 2409.819263555232, 2410.7348191698898, 2455.0793452455327, 2460.372604985726, 2146.5599552091785, 2375.168690958168, 2420.165043060141, 2423.9272821815375, 2424.5756992526235, 2314.9884250578725, 2357.5992081346126, 2363.8227132964926, 2367.2188177901253, 2368.0882860098814, 2348.765123487655, 2358.9764102358927, 2385.9045638174453, 2387.9641805372935, 2393.6409538569355, 8156.388270879707, 8231.42380033398, 8236.258736713664, 8237.46456480333, 8241.50550966944, 4907.1657818171, 5031.899362012776, 5033.597312215051, 5034.521720436589, 5035.823746168903, 3191.760617953663, 3207.23026473647, 3241.9837900810344, 3260.625028121749, 3263.1515805890403, 1056.4581942280554, 1063.9042486176204, 1075.9462026898702, 1885.962280754391, 1889.631521853239, 10971.50628221724, 11112.166587505888, 11131.33212007274, 11135.276207046516, 11147.999999999918, 4259.701820872509, 4322.459692538433, 4388.4733831940375, 4518.4126063612175, 4525.072360166158, 9355.812883742286, 9371.812563748686, 9375.812483750287, 9376.578053987587, 9376.62493500266, 4980.402351717817, 4994.625403094782, 5004.749762511859, 5011.373578302712, 5015.824446144367, 4579.129965306581, 4582.24392975162, 4623.052274283831, 4626.629869610423, 4629.467611224685, 3682.1715114099115, 3705.5368078990127, 3715.758475699071, 3738.4392341149046, 3763.9435408468894, 1095.8849420618283, 1098.5001824169983, 1134.1153899958047, 1134.3420815926743, 1138.5104405105883, 1128.3399211461387, 1178.5168081086713, 1192.8986036186848, 1200.5677955935855, 1212.405921098673, 7783.727569535153, 7843.56860372685, 7847.097583777824, 7856.175101614363, 8057.5165490070785, 1229.0229267732175, 1233.2396947781556, 1234.3087870792385, 1237.6967642927361, 1241.0815996162773, 6251.78118765841, 6371.681415929185, 6424.903626445607, 6431.903521447182, 6441.227052753699, 9772.951135244279, 10004.249681273788, 10108.24188185889, 10117.140043096271, 10131.392116473035, 9555.092266234697, 10001.24990625706, 10006.79986400268, 10012.098911098035, 10042.648507302209, 7810.843783124305, 7886.093099293655, 7891.368690504861, 7896.84206315859, 7911.011123609549, 7866.842663146591, 7912.723054693058, 7936.127026027248, 7937.960310198412, 8010.799730006796, 8319.750407487785, 8646.351523635627, 8968.05835387301, 9065.365424420228, 9071.047540008203, 8656.697015604424, 8791.208791208823, 8818.603162857815, 9235.676751313671, 9363.297752668714, 7518.812029699301, 7616.428767842323, 7625.542467451971, 7625.6949722010495, 7634.961825190839, 8014.318782903519, 8173.019237691515, 8175.345972322319, 8182.304504117216, 8193.0, 6360.459360954185, 7666.0, 7754.534727916343, 7782.0, 7894.171112033154, 7284.307990740871, 7497.10034795828, 7521.3982881368265, 7595.5442673439775, 7623.16145224036, 7411.777646670608, 7425.0, 7433.479656423999, 7446.813829654302, 7446.851062978819, 6837.829054273683, 6844.794656160124, 6926.619035953071, 6930.722771089302, 6951.096357473496, 7152.9284707152265, 7230.819229519304, 7231.204567497466, 7234.6020968847215, 7240.891386629311], "unit": "ms", "name": "mootools.html", "value": 4794.119387780854}, {"lowerIsBetter": false, "replicates": [347.0715835140997, 348.280800147695, 352.55049811490346, 357.3460567162093, 363.1284916201117, 6097.451229389354, 6098.573099883004, 6107.419795119467, 6138.263408390993, 6145.8156255312415, 5787.768489260425, 5850.707464626762, 5938.0499120140785, 6018.849528761783, 6022.036474164134, 4591.334256532801, 4604.378408914804, 4611.492735799059, 4621.353010578522, 4651.906961860761, 4640.86077417678, 4661.160991021615, 4663.4637016743, 4675.719456832584, 4676.766161691919, 4502.639788816903, 4591.724496530219, 4607.677462577621, 4685.1566717990745, 4704.341392205077, 4674.813007479696, 4684.273937539687, 4689.109069276827, 4689.695169813951, 4691.62467002639, 4146.502419709624, 4157.10622216225, 4163.208990291865, 4172.728772629769, 4175.895602609929, 5668.376478587352, 5732.770689172428, 5736.168255602956, 5742.310922689304, 5747.482726554588, 5411.594130440233, 5457.372402173722, 5457.617966742329, 5462.726863656841, 5463.617546771728, 6342.270638876542, 6356.332585078592, 6359.618422894641, 6360.777372791977, 6377.872442551123, 4595.241785105453, 4603.378543896598, 4606.60843828275, 4607.14767767964, 4627.051454451863, 6472.093906853044, 6545.280019197884, 6582.93417065828, 6599.3070727573395, 6623.701933413008, 4694.131585656664, 4700.788464519036, 4701.200795864711, 4703.600193983481, 4735.668503204743, 4788.329633851264, 4799.736014519235, 4802.183628783116, 4803.375561177025, 4808.35087263222, 5407.486288802557, 5410.567154627621, 5420.891582168413, 5445.754941027663, 5449.910017996416, 6266.937330626726, 6408.359164083648, 6410.23077230726, 6413.518986075969, 6420.839479012969, 1685.9041622945183, 1690.509752171881, 1706.4880535839447, 1707.940222092221, 1709.7606335113094, 1152.9769404611861, 1153.1581945180067, 1165.0970497864223, 1175.1949914308725, 1196.216478206776, 6300.905486417708, 6307.148534947723, 6308.27454842699, 6309.0, 6311.873762524816, 6612.0412540181405, 6618.238902526175, 6621.9006714899315, 6622.801315960529, 6622.966885165543, 6117.96941015292, 6231.9688401557705, 6237.376262373727, 6353.523485738496, 6366.204224471941, 3567.661072198137, 3569.0, 3585.6414358564457, 3594.6045934947133, 3598.9100272493397, 6861.4853885958755, 6878.3465570770695, 6888.345607167311, 6891.896621550681, 6896.517243792886, 6660.333966603301, 6818.590884546943, 6833.214180369321, 6834.2140653825445, 6843.213030501456, 6883.27725588816, 6889.276625954303, 6894.310568943066, 6899.758508452281, 6907.240203577602, 6649.767258145942, 6656.201255849329, 6657.567258128206, 6659.633720145244, 6662.900056499253, 6401.679916004275, 6590.373914478117, 6608.966955165193, 6621.635810030494, 6623.3376662333385, 6917.619530925847, 6947.0, 6954.930450695429, 6958.721651133898, 6959.0, 4547.1815073287125, 4568.771561421982, 4573.314002899591, 4574.016586433932, 4577.130345234528, 6604.240512341143, 6661.967395053774, 6670.866582668223, 6710.429613482908, 6711.429528490133, 6503.122078519528, 6514.315996820265, 6516.967415163083, 6517.250516190509, 6522.28254891971, 6504.38208370204, 6506.869862602816, 6513.250976137805, 6516.315786842503, 6530.47756179495, 6280.717367718555, 6411.647359395091, 6420.678966051585, 6428.939225027818, 6431.099646049651, 6515.2833188348295, 6530.314316996647, 6537.8692426150255, 6541.247756508063, 6542.182227221598, 6262.874742505215, 6387.552871298964, 6393.200849893763, 6395.16862807832, 6395.744170233139], "unit": "ms", "name": "prototype.html", "value": 4906.063315481579}, {"lowerIsBetter": false, "replicates": [857.4298091768974, 882.5190271302143, 951.5908159491418, 951.6241084771514, 957.9521023948805, 13430.597082087528, 13675.521356752492, 13687.794683079761, 13690.178589284638, 13702.109362891417, 13252.93373533131, 13773.862261377382, 13781.17312961223, 13787.310634468287, 13817.723645527083, 733.7945375294926, 737.4727070144845, 738.5753191914644, 902.7111324376218, 907.2243232036612, 901.4726385064745, 903.5798353765498, 905.7599736069947, 905.7871400220943, 905.85053466178, 937.5827756648295, 938.6573900526292, 943.7876477792477, 946.8958414574391, 947.4552132523792, 931.6320053578867, 937.0208132501552, 938.324406427378, 939.1547607153548, 940.05994005994, 563.4140493886354, 564.8277275430992, 706.1702499563045, 707.1019804847814, 710.0946293475808, 13886.4445422183, 14052.789208161783, 14074.507392241325, 14087.788683169761, 14097.71804563903, 12772.233665980071, 13633.318334083257, 13641.795373069313, 13831.861681383258, 13935.930320348334, 11158.888411115866, 11240.606578769704, 11249.887501124966, 11253.887461125365, 11300.265482743678, 3866.1881004988986, 3880.2821478026653, 3910.804459777, 3923.2153569286256, 3951.091249012743, 45928.77035614834, 45977.77011114923, 46583.76708116438, 46957.29564056542, 47024.294635580496, 10506.684799456028, 10576.418296993585, 10599.947000264949, 10773.030427261585, 11078.944605277084, 18470.630587388176, 18630.627387452445, 18631.813681863277, 18634.25462981493, 18676.719849202273, 7048.577085374894, 7052.294770523009, 7279.162896266891, 7390.852182956418, 7397.889031664529, 4504.012394724276, 4516.23224051912, 4551.681382303207, 4568.88577785558, 4606.216943119678, 3132.7023932726356, 3181.06158683189, 3191.2181515528846, 3192.1221664042573, 3192.154079169022, 562.8480310316191, 564.6245246910759, 566.2044827018046, 572.7107810555643, 578.308920839601, 713.375796178344, 714.7212587091038, 717.7703134996856, 727.8762610356252, 747.2751431111813, 8881.24509416694, 8972.551372431351, 9063.093690630883, 9067.591958361889, 9131.726048218565, 709.7274408063429, 731.436793668872, 739.9963000185072, 742.3356096293787, 754.5661244784271, 3875.748076375027, 4010.1779135277197, 4018.216447792711, 4050.9797451012555, 4088.550259471456, 57970.71014644984, 58323.0, 58604.4139558599, 58617.12074318888, 58653.41346586565, 51496.74251628793, 51591.0, 51739.0, 51972.74013629908, 51999.48000519946, 45964.77017614891, 46249.30626040612, 47530.5246947533, 47618.76190619094, 47862.0, 8497.320214382711, 8684.826303473768, 8759.080296569024, 9315.580798864203, 9390.906090939003, 34118.31763364669, 34246.14384640404, 35673.0, 35900.82049589735, 36076.27847442984, 43825.78087109544, 44190.33714494349, 44190.558094418644, 45963.0, 45975.31037034514, 11299.3785341807, 11780.823287650863, 12044.578439754567, 12142.0, 12654.746905061664, 12861.164024338388, 13552.186868787905, 13603.727925441237, 13610.931945340606, 13805.792913106512, 11168.609098681507, 11433.0, 11479.139064570358, 12095.939520302341, 12144.14990950625, 12044.0, 12172.2696638202, 12193.0, 12203.389830508262, 12493.312867791996, 11806.173567850168, 11842.881571184178, 12047.638570842888, 12087.0, 12090.818637720617, 10172.491375431347, 10373.066424021874, 10806.513706883052, 11033.227674062739, 11164.162687798611, 9482.336236463381, 9525.190358819578, 9695.369800962915, 9948.403095814274, 10558.577656893945], "unit": "ms", "name": "yui.html", "value": 6629.758902556529}], "name": "dromaeo_css", "value": 7412.8392647950395}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 104.0, 109.0, 109.0, 108.0, 98.0, 107.0, 108.0, 107.0], "unit": "ms", "name": "ai-astar", "value": 106.7}, {"lowerIsBetter": true, "replicates": [151.0, 122.0, 145.0, 157.0, 130.0, 146.0, 159.0, 122.0, 159.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.3}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 159.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 74.0, 145.0, 74.0, 72.0, 73.0, 74.0, 149.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 138.0, 91.0, 92.0, 93.0, 138.0, 91.0, 92.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 105.4}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 147.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 58.0, 56.0, 57.0, 58.0, 57.0, 59.0, 57.0, 59.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 77.0, 77.0, 77.0, 78.0, 77.0, 78.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.7}, {"lowerIsBetter": true, "replicates": [114.0, 117.0, 112.0, 124.0, 110.0, 120.0, 118.0, 115.0, 115.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.1}, {"lowerIsBetter": true, "replicates": [140.0, 143.0, 155.0, 141.0, 143.0, 134.0, 135.0, 141.0, 141.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.5}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 62.0, 62.0, 63.0, 63.0, 63.0, 64.0, 64.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.0}], "name": "kraken", "value": 1468.8999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.20361247947454], "unit": "score", "name": "Richards", "value": 214.98917999999992}, {"lowerIsBetter": false, "replicates": [165.15276630883568], "unit": "score", "name": "DeltaBlue", "value": 400.34449000000006}, {"lowerIsBetter": false, "replicates": [278.2415136338342], "unit": "score", "name": "Encrypt", "value": 956.654514}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [958.7727708533077], "unit": "score", "name": "RayTrace", "value": 771.8085270000001}, {"lowerIsBetter": false, "replicates": [542.8881650380022], "unit": "score", "name": "Earley", "value": 1227.624846}, {"lowerIsBetter": false, "replicates": [10562.5], "unit": "score", "name": "Boyer", "value": 63.09708875739642}, {"lowerIsBetter": false, "replicates": [24609.756097560974], "unit": "score", "name": "RegExp", "value": 37.01722993062436}, {"lowerIsBetter": false, "replicates": [517.4537987679671], "unit": "score", "name": "Splay", "value": 157.48459126984125}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23312.544025769963}]} 09:32:50 INFO - RETURN: dromaeo_css: 7412.84 09:32:50 INFO - RETURN: kraken: 1468.90 09:32:50 INFO - RETURN: v8_7: 23312.54 09:32:50 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23312.54"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1468.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7412.84"}}} 09:32:50 INFO - Return code: 0 09:32:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:32:50 INFO - # TBPL SUCCESS # 09:32:50 INFO - Running post-action listener: _resource_record_post_action 09:32:50 INFO - Running post-run listener: _resource_record_post_run 09:32:51 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 0; Write bytes: 505323520; Read time: 0; Write time: 379832 09:32:51 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239177728; Read time: 0; Write time: 222800 09:32:51 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 0; Write bytes: 265547776; Read time: 0; Write time: 156196 09:32:51 INFO - Running post-run listener: _upload_blobber_files 09:32:51 INFO - Blob upload gear active. 09:32:51 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:32:51 INFO - Copying logs to upload dir... 09:32:51 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.855878 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-08 09:32:50.389168) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-08 09:32:50.392314) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010005 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454942704/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-08 09:32:50.442991) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:32:50.443311) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1454951515.483735-150774937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-08 09:32:50.492830) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:32:50.493230) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-08 09:32:50.493525) ========= ========= Total master_lag: 0.25 =========