builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1456424059.79 results: success (0) buildid: 20160225075528 builduid: ebdfb8194b164eec8c16d96f1de69337 revision: 30883d70b037 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.789739) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.790234) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.790540) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009039 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.815454) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.815762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.830131) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.830551) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.880079) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.880390) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.880742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:19.881019) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 10:14:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-02-25 10:14:20 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187901 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:20.080770) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:20.081117) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:20.131159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-25 10:14:20.131525) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 30883d70b037 --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 30883d70b037 --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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 10:14:20,262 Setting DEBUG logging. 2016-02-25 10:14:20,262 attempt 1/10 2016-02-25 10:14:20,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/30883d70b037?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 10:14:21,591 unpacking tar archive at: mozilla-beta-30883d70b037/testing/mozharness/ program finished with exit code 0 elapsedTime=1.644331 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-25 10:14:21.788727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:21.789039) ========= echo 30883d70b037 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'30883d70b037'] 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 30883d70b037 program finished with exit code 0 elapsedTime=0.004641 script_repo_revision: '30883d70b037' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:21.839978) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:21.840298) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:14:21.851739) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-25 10:14:21.852107) ========= /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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:14:22 INFO - MultiFileLogger online at 20160225 10:14:22 in /builds/slave/test-pgo 10:14:22 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 10:14:22 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:14:22 INFO - {'append_to_log': False, 10:14:22 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:14:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:14:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:14:22 INFO - 'branch': 'Mozilla-Beta', 10:14:22 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:22 INFO - 'config_files': ('talos/linux_config.py',), 10:14:22 INFO - 'default_actions': ('clobber', 10:14:22 INFO - 'read-buildbot-config', 10:14:22 INFO - 'download-and-extract', 10:14:22 INFO - 'populate-webroot', 10:14:22 INFO - 'create-virtualenv', 10:14:22 INFO - 'install', 10:14:22 INFO - 'run-tests'), 10:14:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:22 INFO - 'download_minidump_stackwalk': True, 10:14:22 INFO - 'download_symbols': 'ondemand', 10:14:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:14:22 INFO - 'tooltool.py': '/tools/tooltool.py', 10:14:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:14:22 INFO - '/tools/misc-python/virtualenv.py')}, 10:14:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:22 INFO - 'installer_path': 'installer.exe', 10:14:22 INFO - 'log_level': 'info', 10:14:22 INFO - 'log_name': 'talos', 10:14:22 INFO - 'log_to_console': True, 10:14:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:14:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:14:22 INFO - 'opt_config_files': (), 10:14:22 INFO - 'pip_index': False, 10:14:22 INFO - 'sps_profile': False, 10:14:22 INFO - 'sps_profile_interval': 0, 10:14:22 INFO - 'suite': 'dromaeojs', 10:14:22 INFO - 'system_bits': '32', 10:14:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:22 INFO - 'title': 'talos-linux64-ix-035', 10:14:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:14:22 INFO - 'use_talos_json': True, 10:14:22 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:14:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:22 INFO - 'work_dir': 'build'} 10:14:22 INFO - ##### 10:14:22 INFO - ##### Running clobber step. 10:14:22 INFO - ##### 10:14:22 INFO - Running pre-action listener: _resource_record_pre_action 10:14:22 INFO - Running main action method: clobber 10:14:22 INFO - rmtree: /builds/slave/test-pgo/build 10:14:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:14:22 INFO - Running post-action listener: _resource_record_post_action 10:14:22 INFO - ##### 10:14:22 INFO - ##### Running read-buildbot-config step. 10:14:22 INFO - ##### 10:14:22 INFO - Running pre-action listener: _resource_record_pre_action 10:14:22 INFO - Running main action method: read_buildbot_config 10:14:22 INFO - Using buildbot properties: 10:14:22 INFO - { 10:14:22 INFO - "properties": { 10:14:22 INFO - "buildnumber": 99, 10:14:22 INFO - "product": "firefox", 10:14:22 INFO - "script_repo_revision": "production", 10:14:22 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:14:22 INFO - "repository": "", 10:14:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:14:22 INFO - "buildid": "20160225075528", 10:14:22 INFO - "slavename": "talos-linux64-ix-035", 10:14:22 INFO - "pgo_build": "True", 10:14:22 INFO - "basedir": "/builds/slave/test-pgo", 10:14:22 INFO - "project": "", 10:14:22 INFO - "platform": "ubuntu64_hw", 10:14:22 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:14:22 INFO - "slavebuilddir": "test", 10:14:22 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:14:22 INFO - "branch": "mozilla-beta", 10:14:22 INFO - "repo_path": "releases/mozilla-beta", 10:14:22 INFO - "stage_platform": "linux64-pgo", 10:14:22 INFO - "builduid": "ebdfb8194b164eec8c16d96f1de69337", 10:14:22 INFO - "revision": "30883d70b037" 10:14:22 INFO - }, 10:14:22 INFO - "sourcestamp": { 10:14:22 INFO - "repository": "", 10:14:22 INFO - "hasPatch": false, 10:14:22 INFO - "project": "", 10:14:22 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:14:22 INFO - "changes": [ 10:14:22 INFO - { 10:14:22 INFO - "category": null, 10:14:22 INFO - "files": [ 10:14:22 INFO - { 10:14:22 INFO - "url": null, 10:14:22 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:14:22 INFO - } 10:14:22 INFO - ], 10:14:22 INFO - "repository": "", 10:14:22 INFO - "rev": "30883d70b037", 10:14:22 INFO - "who": "sendchange", 10:14:22 INFO - "when": 1456423982, 10:14:22 INFO - "number": 7654210, 10:14:22 INFO - "comments": "Bug 1245277 - loop addon needs to respect prior set default prefs, not restartless. r=standard8, a=sylvestre", 10:14:22 INFO - "project": "", 10:14:22 INFO - "at": "Thu 25 Feb 2016 10:13:02", 10:14:22 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:14:22 INFO - "revlink": "", 10:14:22 INFO - "properties": [ 10:14:22 INFO - [ 10:14:22 INFO - "buildid", 10:14:22 INFO - "20160225075528", 10:14:22 INFO - "Change" 10:14:22 INFO - ], 10:14:22 INFO - [ 10:14:22 INFO - "builduid", 10:14:22 INFO - "ebdfb8194b164eec8c16d96f1de69337", 10:14:22 INFO - "Change" 10:14:22 INFO - ], 10:14:22 INFO - [ 10:14:22 INFO - "pgo_build", 10:14:22 INFO - "True", 10:14:22 INFO - "Change" 10:14:22 INFO - ] 10:14:22 INFO - ], 10:14:22 INFO - "revision": "30883d70b037" 10:14:22 INFO - } 10:14:22 INFO - ], 10:14:22 INFO - "revision": "30883d70b037" 10:14:22 INFO - } 10:14:22 INFO - } 10:14:22 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:14:22 INFO - Running post-action listener: _resource_record_post_action 10:14:22 INFO - ##### 10:14:22 INFO - ##### Running download-and-extract step. 10:14:22 INFO - ##### 10:14:22 INFO - Running pre-action listener: _resource_record_pre_action 10:14:22 INFO - Running main action method: download_and_extract 10:14:22 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:14: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')]} 10:14:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/test_packages.json 10:14:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:14:22 INFO - Downloaded 1270 bytes. 10:14:22 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:14:22 INFO - Using the following test package requirements: 10:14:22 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:14:22 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:14:22 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'jsshell-linux-x86_64.zip'], 10:14:22 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:14:22 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:14:22 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:14:22 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:14:22 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:14:22 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:14:22 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:14:22 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:14:22 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:14: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')]} 10:14:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:14:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:29 INFO - Downloaded 21584005 bytes. 10:14:29 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 10:14:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:14:29 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 10:14:30 INFO - Return code: 0 10:14:30 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 10:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:14:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:30 INFO - Downloaded 21584005 bytes. 10:14:30 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 10:14:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:14:30 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 10:14:32 INFO - Return code: 0 10:14:32 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')]} 10:14:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:14:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:37 INFO - Downloaded 11132038 bytes. 10:14:37 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 10:14:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:14:37 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 10:14:37 INFO - Return code: 0 10:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:14:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/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 10:14:42 INFO - Downloaded 57954773 bytes. 10:14:42 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:14:42 INFO - mkdir: /builds/slave/test-pgo/properties 10:14:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:14:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:14:42 INFO - Contents: 10:14:42 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:14:42 INFO - Running post-action listener: _resource_record_post_action 10:14:42 INFO - Running post-action listener: set_extra_try_arguments 10:14:42 INFO - ##### 10:14:42 INFO - ##### Running populate-webroot step. 10:14:42 INFO - ##### 10:14:42 INFO - Running pre-action listener: _resource_record_pre_action 10:14:42 INFO - Running main action method: populate_webroot 10:14:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:14:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:14:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:14:42 INFO - '--tppagecycles', 10:14:42 INFO - '7'], 10:14:42 INFO - 'tests': ['tsvgm']}}, 10:14:42 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:14:42 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:14:42 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:14:42 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:14:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:14:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:14:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'tests': ['damp', 'tps']}, 10:14:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['damp', 'tps']}, 10:14:42 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:14:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:14:42 INFO - 'other': {'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'other_l64': {'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'other_nol64': {'tests': ['a11yr', 10:14:42 INFO - 'ts_paint', 10:14:42 INFO - 'tpaint', 10:14:42 INFO - 'sessionrestore', 10:14:42 INFO - 'sessionrestore_no_auto_restore']}, 10:14:42 INFO - 'svgr': {'tests': ['tsvgx', 10:14:42 INFO - 'tsvgr_opacity', 10:14:42 INFO - 'tart', 10:14:42 INFO - 'tscrollx', 10:14:42 INFO - 'cart']}, 10:14:42 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['tsvgx', 10:14:42 INFO - 'tsvgr_opacity', 10:14:42 INFO - 'tart', 10:14:42 INFO - 'tscrollx', 10:14:42 INFO - 'cart']}, 10:14:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'tests': ['tp5o']}, 10:14:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'talos_options': ['--e10s'], 10:14:42 INFO - 'tests': ['tp5o']}, 10:14:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'talos_options': ['--xperf_path', 10:14:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:14:42 INFO - 'tests': ['tp5n']}, 10:14:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:14:42 INFO - 'talos_options': ['--e10s', 10:14:42 INFO - '--xperf_path', 10:14:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:14:42 INFO - 'tests': ['tp5n']}}, 10:14:42 INFO - 'talos.zip': {'path': '', 10:14:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:14:42 INFO - Running post-action listener: _resource_record_post_action 10:14:42 INFO - ##### 10:14:42 INFO - ##### Running create-virtualenv step. 10:14:42 INFO - ##### 10:14:42 INFO - Running pre-action listener: _resource_record_pre_action 10:14:42 INFO - Running main action method: create_virtualenv 10:14:42 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:14:42 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 10:14:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:14:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:14:42 INFO - Using real prefix '/usr' 10:14:42 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:14:43 INFO - Installing distribute.............................................................................................................................................................................................done. 10:14:45 INFO - Installing pip.................done. 10:14:45 INFO - Return code: 0 10:14:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:14:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:14:45 INFO - Using env: {'DISPLAY': ':0', 10:14:45 INFO - 'HOME': '/home/cltbld', 10:14:45 INFO - 'LANG': 'en_US.UTF-8', 10:14:45 INFO - 'LANGUAGE': 'en_US:en', 10:14:45 INFO - 'LOGNAME': 'cltbld', 10:14:45 INFO - 'MAIL': '/var/mail/cltbld', 10:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:45 INFO - 'MOZ_NO_REMOTE': '1', 10:14:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:45 INFO - 'NO_EM_RESTART': '1', 10:14:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:45 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:45 INFO - 'SHELL': '/bin/bash', 10:14:45 INFO - 'SHLVL': '1', 10:14:45 INFO - 'TERM': 'linux', 10:14:45 INFO - 'TMOUT': '86400', 10:14:45 INFO - 'USER': 'cltbld', 10:14:45 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:45 INFO - '_': '/tools/buildbot/bin/python'} 10:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:14:45 INFO - Cleaning up... 10:14:45 INFO - Return code: 0 10:14:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 10:14:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:14:45 INFO - Using env: {'DISPLAY': ':0', 10:14:45 INFO - 'HOME': '/home/cltbld', 10:14:45 INFO - 'LANG': 'en_US.UTF-8', 10:14:45 INFO - 'LANGUAGE': 'en_US:en', 10:14:45 INFO - 'LOGNAME': 'cltbld', 10:14:45 INFO - 'MAIL': '/var/mail/cltbld', 10:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:45 INFO - 'MOZ_NO_REMOTE': '1', 10:14:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:45 INFO - 'NO_EM_RESTART': '1', 10:14:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:45 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:45 INFO - 'SHELL': '/bin/bash', 10:14:45 INFO - 'SHLVL': '1', 10:14:45 INFO - 'TERM': 'linux', 10:14:45 INFO - 'TMOUT': '86400', 10:14:45 INFO - 'USER': 'cltbld', 10:14:45 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:45 INFO - '_': '/tools/buildbot/bin/python'} 10:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:45 INFO - Downloading/unpacking psutil>=0.7.1 10:14:45 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 10:14:45 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 10:14:45 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 10:14:45 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 10:14:45 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 10:14:45 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 10:14:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:14:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:14:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:14:47 INFO - Installing collected packages: psutil 10:14:47 INFO - Running setup.py install for psutil 10:14:47 INFO - building 'psutil._psutil_linux' extension 10:14:47 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 10:14:47 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 10:14:47 INFO - building 'psutil._psutil_posix' extension 10:14:47 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 10:14:47 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 10:14:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:14:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:14:48 INFO - Successfully installed psutil 10:14:48 INFO - Cleaning up... 10:14:48 INFO - Return code: 0 10:14:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:14:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:14:48 INFO - Using env: {'DISPLAY': ':0', 10:14:48 INFO - 'HOME': '/home/cltbld', 10:14:48 INFO - 'LANG': 'en_US.UTF-8', 10:14:48 INFO - 'LANGUAGE': 'en_US:en', 10:14:48 INFO - 'LOGNAME': 'cltbld', 10:14:48 INFO - 'MAIL': '/var/mail/cltbld', 10:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:48 INFO - 'MOZ_NO_REMOTE': '1', 10:14:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:48 INFO - 'NO_EM_RESTART': '1', 10:14:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:48 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:48 INFO - 'SHELL': '/bin/bash', 10:14:48 INFO - 'SHLVL': '1', 10:14:48 INFO - 'TERM': 'linux', 10:14:48 INFO - 'TMOUT': '86400', 10:14:48 INFO - 'USER': 'cltbld', 10:14:48 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:48 INFO - '_': '/tools/buildbot/bin/python'} 10:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:14:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:14:50 INFO - Installing collected packages: mozsystemmonitor 10:14:50 INFO - Running setup.py install for mozsystemmonitor 10:14:50 INFO - Successfully installed mozsystemmonitor 10:14:50 INFO - Cleaning up... 10:14:50 INFO - Return code: 0 10:14:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 10:14:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:14:50 INFO - Using env: {'DISPLAY': ':0', 10:14:50 INFO - 'HOME': '/home/cltbld', 10:14:50 INFO - 'LANG': 'en_US.UTF-8', 10:14:50 INFO - 'LANGUAGE': 'en_US:en', 10:14:50 INFO - 'LOGNAME': 'cltbld', 10:14:50 INFO - 'MAIL': '/var/mail/cltbld', 10:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:50 INFO - 'MOZ_NO_REMOTE': '1', 10:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:50 INFO - 'NO_EM_RESTART': '1', 10:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:50 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:50 INFO - 'SHELL': '/bin/bash', 10:14:50 INFO - 'SHLVL': '1', 10:14:50 INFO - 'TERM': 'linux', 10:14:50 INFO - 'TMOUT': '86400', 10:14:50 INFO - 'USER': 'cltbld', 10:14:50 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:50 INFO - '_': '/tools/buildbot/bin/python'} 10:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:50 INFO - Downloading/unpacking blobuploader==1.2.4 10:14:50 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 10:14:50 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 10:14:50 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 10:14:50 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 10:14:50 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 10:14:50 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 10:14:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:14:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:14:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:14:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:53 INFO - Downloading docopt-0.6.1.tar.gz 10:14:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:14:53 INFO - Installing collected packages: blobuploader, requests, docopt 10:14:53 INFO - Running setup.py install for blobuploader 10:14:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:14:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:14:53 INFO - Running setup.py install for requests 10:14:53 INFO - Running setup.py install for docopt 10:14:53 INFO - Successfully installed blobuploader requests docopt 10:14:53 INFO - Cleaning up... 10:14:53 INFO - Return code: 0 10:14:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:14:53 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')]} 10:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:53 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')]} 10:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:53 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 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:53 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 10:14:53 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 10:14:53 INFO - Using env: {'DISPLAY': ':0', 10:14:53 INFO - 'HOME': '/home/cltbld', 10:14:53 INFO - 'LANG': 'en_US.UTF-8', 10:14:53 INFO - 'LANGUAGE': 'en_US:en', 10:14:53 INFO - 'LOGNAME': 'cltbld', 10:14:53 INFO - 'MAIL': '/var/mail/cltbld', 10:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:53 INFO - 'MOZ_NO_REMOTE': '1', 10:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:53 INFO - 'NO_EM_RESTART': '1', 10:14:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:53 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:53 INFO - 'SHELL': '/bin/bash', 10:14:53 INFO - 'SHLVL': '1', 10:14:53 INFO - 'TERM': 'linux', 10:14:53 INFO - 'TMOUT': '86400', 10:14:53 INFO - 'USER': 'cltbld', 10:14:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:53 INFO - '_': '/tools/buildbot/bin/python'} 10:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:14:53 INFO - Running setup.py (path:/tmp/pip-G0akHN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:14:54 INFO - Running setup.py (path:/tmp/pip-TgHDyD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:14:54 INFO - Running setup.py (path:/tmp/pip-vN0h7u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:14:54 INFO - Running setup.py (path:/tmp/pip-2orPv0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:14:54 INFO - Running setup.py (path:/tmp/pip-bLkZ6P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:14:54 INFO - Running setup.py (path:/tmp/pip-NIDfVm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:14:54 INFO - Running setup.py (path:/tmp/pip-rQQTxx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:14:54 INFO - Running setup.py (path:/tmp/pip-deGUEW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:14:54 INFO - Running setup.py (path:/tmp/pip-M4Iee3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:14:54 INFO - Running setup.py (path:/tmp/pip-lTDYra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:14:54 INFO - Running setup.py (path:/tmp/pip-ts6vS9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:14:54 INFO - Running setup.py (path:/tmp/pip-gn_CkP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:14:54 INFO - Running setup.py (path:/tmp/pip-cfS3Eg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:14:54 INFO - Running setup.py (path:/tmp/pip-WFk4nv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:14:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:14:54 INFO - Running setup.py (path:/tmp/pip-mjiw2o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:14:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:14:55 INFO - Running setup.py (path:/tmp/pip-kQd9PM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:14:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:14:55 INFO - Running setup.py (path:/tmp/pip-wbdLuO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:14:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:14:55 INFO - Running setup.py install for manifestparser 10:14:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Running setup.py install for mozcrash 10:14:55 INFO - Running setup.py install for mozdebug 10:14:55 INFO - Running setup.py install for mozdevice 10:14:55 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Running setup.py install for mozfile 10:14:55 INFO - Running setup.py install for mozhttpd 10:14:55 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Running setup.py install for mozinfo 10:14:55 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Running setup.py install for mozInstall 10:14:55 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:14:55 INFO - Running setup.py install for mozleak 10:14:56 INFO - Running setup.py install for mozlog 10:14:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Running setup.py install for moznetwork 10:14:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Running setup.py install for mozprocess 10:14:56 INFO - Running setup.py install for mozprofile 10:14:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Running setup.py install for mozrunner 10:14:56 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Running setup.py install for mozscreenshot 10:14:56 INFO - Running setup.py install for moztest 10:14:56 INFO - Running setup.py install for mozversion 10:14:56 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:14:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:14:56 INFO - Cleaning up... 10:14:56 INFO - Return code: 0 10:14:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:14:56 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')]} 10:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:56 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')]} 10:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:56 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 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:14:56 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 10:14:56 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 10:14:56 INFO - Using env: {'DISPLAY': ':0', 10:14:56 INFO - 'HOME': '/home/cltbld', 10:14:56 INFO - 'LANG': 'en_US.UTF-8', 10:14:56 INFO - 'LANGUAGE': 'en_US:en', 10:14:56 INFO - 'LOGNAME': 'cltbld', 10:14:56 INFO - 'MAIL': '/var/mail/cltbld', 10:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:56 INFO - 'MOZ_NO_REMOTE': '1', 10:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:14:56 INFO - 'NO_EM_RESTART': '1', 10:14:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:14:56 INFO - 'PWD': '/builds/slave/test-pgo', 10:14:56 INFO - 'SHELL': '/bin/bash', 10:14:56 INFO - 'SHLVL': '1', 10:14:56 INFO - 'TERM': 'linux', 10:14:56 INFO - 'TMOUT': '86400', 10:14:56 INFO - 'USER': 'cltbld', 10:14:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:56 INFO - '_': '/tools/buildbot/bin/python'} 10:14:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:14:57 INFO - Running setup.py (path:/tmp/pip-1UYx7v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:14:57 INFO - Running setup.py (path:/tmp/pip-jMfObr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:14:57 INFO - Running setup.py (path:/tmp/pip-nBWpKq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:14:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:14:57 INFO - Running setup.py (path:/tmp/pip-sn0IZ3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:14:57 INFO - Running setup.py (path:/tmp/pip-lBE_vT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:14:57 INFO - Running setup.py (path:/tmp/pip-4ztPZN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:14:57 INFO - Running setup.py (path:/tmp/pip-lYdPST-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:14:57 INFO - Running setup.py (path:/tmp/pip-_Jzx3E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:14:57 INFO - Running setup.py (path:/tmp/pip-uIPauz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:14:57 INFO - Running setup.py (path:/tmp/pip-HeryPa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:14:57 INFO - Running setup.py (path:/tmp/pip-jLfum5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:14:57 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)) 10:14:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:14:57 INFO - Running setup.py (path:/tmp/pip-_UODpG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 10:14:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:14:58 INFO - Running setup.py (path:/tmp/pip-bY4ois-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 10:14:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:14:58 INFO - Running setup.py (path:/tmp/pip-felWml-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 10:14:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:14:58 INFO - Running setup.py (path:/tmp/pip-LFv3Z0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 10:14:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:14:58 INFO - Running setup.py (path:/tmp/pip-AD7WT9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 10:14:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:14:58 INFO - Running setup.py (path:/tmp/pip-BoutHm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:00 INFO - Downloading blessings-1.6.tar.gz 10:15:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:00 INFO - Installing collected packages: blessings 10:15:00 INFO - Running setup.py install for blessings 10:15:00 INFO - Successfully installed blessings 10:15:00 INFO - Cleaning up... 10:15:00 INFO - Return code: 0 10:15:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:15:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:15:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:15:00 INFO - Reading from file tmpfile_stdout 10:15:00 INFO - Current package versions: 10:15:00 INFO - argparse == 1.2.1 10:15:00 INFO - blessings == 1.6 10:15:00 INFO - blobuploader == 1.2.4 10:15:00 INFO - docopt == 0.6.1 10:15:00 INFO - manifestparser == 1.1 10:15:00 INFO - mozInstall == 1.12 10:15:00 INFO - mozcrash == 0.16 10:15:00 INFO - mozdebug == 0.1 10:15:00 INFO - mozdevice == 0.47 10:15:00 INFO - mozfile == 1.2 10:15:00 INFO - mozhttpd == 0.7 10:15:00 INFO - mozinfo == 0.9 10:15:00 INFO - mozleak == 0.1 10:15:00 INFO - mozlog == 3.1 10:15:00 INFO - moznetwork == 0.27 10:15:00 INFO - mozprocess == 0.22 10:15:00 INFO - mozprofile == 0.27 10:15:00 INFO - mozrunner == 6.11 10:15:00 INFO - mozscreenshot == 0.1 10:15:00 INFO - mozsystemmonitor == 0.0 10:15:00 INFO - moztest == 0.7 10:15:00 INFO - mozversion == 1.4 10:15:00 INFO - psutil == 3.1.1 10:15:00 INFO - requests == 1.2.3 10:15:00 INFO - wsgiref == 0.1.2 10:15:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:15: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')]} 10:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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')]} 10:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:00 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 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:15:00 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 10:15:00 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 10:15:00 INFO - Using env: {'DISPLAY': ':0', 10:15:00 INFO - 'HOME': '/home/cltbld', 10:15:00 INFO - 'LANG': 'en_US.UTF-8', 10:15:00 INFO - 'LANGUAGE': 'en_US:en', 10:15:00 INFO - 'LOGNAME': 'cltbld', 10:15:00 INFO - 'MAIL': '/var/mail/cltbld', 10:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:00 INFO - 'MOZ_NO_REMOTE': '1', 10:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:00 INFO - 'NO_EM_RESTART': '1', 10:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:00 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:00 INFO - 'SHELL': '/bin/bash', 10:15:00 INFO - 'SHLVL': '1', 10:15:00 INFO - 'TERM': 'linux', 10:15:00 INFO - 'TMOUT': '86400', 10:15:00 INFO - 'USER': 'cltbld', 10:15:00 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:00 INFO - '_': '/tools/buildbot/bin/python'} 10:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 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)) 10:15:00 INFO - Cleaning up... 10:15:00 INFO - Return code: 0 10:15:00 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:15: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')]} 10:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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')]} 10:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:00 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 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:15:00 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 10:15:00 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 10:15:00 INFO - Using env: {'DISPLAY': ':0', 10:15:00 INFO - 'HOME': '/home/cltbld', 10:15:00 INFO - 'LANG': 'en_US.UTF-8', 10:15:00 INFO - 'LANGUAGE': 'en_US:en', 10:15:00 INFO - 'LOGNAME': 'cltbld', 10:15:00 INFO - 'MAIL': '/var/mail/cltbld', 10:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:00 INFO - 'MOZ_NO_REMOTE': '1', 10:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:00 INFO - 'NO_EM_RESTART': '1', 10:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:00 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:00 INFO - 'SHELL': '/bin/bash', 10:15:00 INFO - 'SHLVL': '1', 10:15:00 INFO - 'TERM': 'linux', 10:15:00 INFO - 'TMOUT': '86400', 10:15:00 INFO - 'USER': 'cltbld', 10:15:00 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:00 INFO - '_': '/tools/buildbot/bin/python'} 10:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:01 INFO - Downloading/unpacking jsonschema 10:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:02 INFO - Downloading/unpacking functools32 (from jsonschema) 10:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:03 INFO - warning: no files found matching '*.txt' 10:15:03 INFO - no previously-included directories found matching 'build' 10:15:03 INFO - no previously-included directories found matching 'dist' 10:15:03 INFO - no previously-included directories found matching '.git*' 10:15:03 INFO - Installing collected packages: jsonschema, functools32 10:15:03 INFO - Running setup.py install for functools32 10:15:03 INFO - warning: no files found matching '*.txt' 10:15:03 INFO - no previously-included directories found matching 'build' 10:15:03 INFO - no previously-included directories found matching 'dist' 10:15:03 INFO - no previously-included directories found matching '.git*' 10:15:03 INFO - Successfully installed jsonschema functools32 10:15:03 INFO - Cleaning up... 10:15:03 INFO - Return code: 0 10:15:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb63759f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21238a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21f0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', '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 10:15:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:15:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:15:03 INFO - Using env: {'DISPLAY': ':0', 10:15:03 INFO - 'HOME': '/home/cltbld', 10:15:03 INFO - 'LANG': 'en_US.UTF-8', 10:15:03 INFO - 'LANGUAGE': 'en_US:en', 10:15:03 INFO - 'LOGNAME': 'cltbld', 10:15:03 INFO - 'MAIL': '/var/mail/cltbld', 10:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:03 INFO - 'MOZ_NO_REMOTE': '1', 10:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:03 INFO - 'NO_EM_RESTART': '1', 10:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:03 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:03 INFO - 'SHELL': '/bin/bash', 10:15:03 INFO - 'SHLVL': '1', 10:15:03 INFO - 'TERM': 'linux', 10:15:03 INFO - 'TMOUT': '86400', 10:15:03 INFO - 'USER': 'cltbld', 10:15:03 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:03 INFO - '_': '/tools/buildbot/bin/python'} 10:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:03 INFO - Downloading/unpacking flake8 10:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:15:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:15:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:06 INFO - no previously-included directories found matching 'docs/_build' 10:15:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:15:06 INFO - Running setup.py install for pep8 10:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:06 INFO - no previously-included directories found matching 'docs/_build' 10:15:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:15:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:15:06 INFO - Cleaning up... 10:15:06 INFO - Return code: 0 10:15:06 INFO - Running post-action listener: _resource_record_post_action 10:15:06 INFO - Running post-action listener: _start_resource_monitoring 10:15:06 INFO - Starting resource monitoring. 10:15:06 INFO - ##### 10:15:06 INFO - ##### Running install step. 10:15:06 INFO - ##### 10:15:06 INFO - Running pre-action listener: _resource_record_pre_action 10:15:06 INFO - Running main action method: install 10:15:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:15:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:15:07 INFO - Reading from file tmpfile_stdout 10:15:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:15:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:15:07 INFO - Reading from file tmpfile_stdout 10:15:07 INFO - Output received: 10:15:07 INFO - Usage: mozinstall [options] installer 10:15:07 INFO - Options: 10:15:07 INFO - -h, --help show this help message and exit 10:15:07 INFO - -d DEST, --destination=DEST 10:15:07 INFO - Directory to install application into. [default: 10:15:07 INFO - "/builds/slave/test-pgo"] 10:15:07 INFO - --app=APP Application being installed. [default: firefox] 10:15:07 INFO - mkdir: /builds/slave/test-pgo/build/application 10:15:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:15:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:15:21 INFO - Reading from file tmpfile_stdout 10:15:21 INFO - Output received: 10:15:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:15:21 INFO - Running post-action listener: _resource_record_post_action 10:15:21 INFO - ##### 10:15:21 INFO - ##### Running run-tests step. 10:15:21 INFO - ##### 10:15:21 INFO - Running pre-action listener: _resource_record_pre_action 10:15:21 INFO - Running main action method: run_tests 10:15:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:15:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:15:21 INFO - Python 2.7.3 10:15:21 INFO - Return code: 0 10:15:21 INFO - grabbing minidump binary from tooltool 10:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x227f350>, '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 0x2280250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22806e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:15:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 10:15:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:15:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:15:21 INFO - Return code: 0 10:15:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:15:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:15:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:15:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:15:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:15:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:15:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:15:22 INFO - Return code: 0 10:15:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:15:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 10:15:22 INFO - Using env: {'DISPLAY': ':0', 10:15:22 INFO - 'HOME': '/home/cltbld', 10:15:22 INFO - 'LANG': 'en_US.UTF-8', 10:15:22 INFO - 'LANGUAGE': 'en_US:en', 10:15:22 INFO - 'LOGNAME': 'cltbld', 10:15:22 INFO - 'MAIL': '/var/mail/cltbld', 10:15:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:15:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:22 INFO - 'MOZ_NO_REMOTE': '1', 10:15:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:22 INFO - 'NO_EM_RESTART': '1', 10:15:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:22 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:15:22 INFO - 'SHELL': '/bin/bash', 10:15:22 INFO - 'SHLVL': '1', 10:15:22 INFO - 'TERM': 'linux', 10:15:22 INFO - 'TMOUT': '86400', 10:15:22 INFO - 'USER': 'cltbld', 10:15:22 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231', 10:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:22 INFO - '_': '/tools/buildbot/bin/python'} 10:15:22 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 10:15:22 INFO - mozversion INFO | application_buildid: 20160225075528 10:15:22 INFO - mozversion INFO | application_changeset: 30883d70b037646afbba598111dc7b48d066155f 10:15:22 INFO - mozversion INFO | application_display_name: Nightly 10:15:22 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:15:22 INFO - mozversion INFO | application_name: Firefox 10:15:23 INFO - mozversion INFO | application_remotingname: firefox 10:15:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:23 INFO - mozversion INFO | application_vendor: Mozilla 10:15:23 INFO - mozversion INFO | application_version: 45.0 10:15:23 INFO - mozversion INFO | platform_buildid: 20160225075528 10:15:23 INFO - mozversion INFO | platform_changeset: 30883d70b037646afbba598111dc7b48d066155f 10:15:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:23 INFO - mozversion INFO | platform_version: 45.0 10:15:23 INFO - 2016-02-25 10:15:22,999 DEBUG : using testdate: 1456424122 10:15:23 INFO - 2016-02-25 10:15:22,999 DEBUG : actual date: 1456424122 10:15:23 INFO - 2016-02-25 10:15:23,007 INFO : starting webserver on 'localhost:46033' 10:15:23 INFO - 2016-02-25 10:15:23,008 INFO : Starting test suite talos-linux64-ix-035 10:15:23 INFO - 2016-02-25 10:15:23,008 INFO : Starting test dromaeo_css 10:15:23 INFO - 2016-02-25 10:15:23,009 DEBUG : operating with platform_type : linux_ 10:15:23 INFO - 2016-02-25 10:15:23,009 INFO : Initialising browser for dromaeo_css test... 10:15:23 INFO - 2016-02-25 10:15:23,025 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdDYAgW/profile http://localhost:46033/getInfo.html 10:15:26 INFO - 2016-02-25 10:15:26,932 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:15:26 INFO - 2016-02-25 10:15:26,932 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:15:26 INFO - 2016-02-25 10:15:26,932 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:15:26 INFO - 2016-02-25 10:15:26,932 DEBUG : BROWSER_OUTPUT: __metrics 10:15:27 INFO - 2016-02-25 10:15:27,093 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:27 INFO - 2016-02-25 10:15:27,797 INFO : Browser initialized. 10:15:27 INFO - 2016-02-25 10:15:27,798 INFO : Running cycle 1/1 for dromaeo_css test... 10:15:27 INFO - 2016-02-25 10:15:27,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdDYAgW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:15:39 INFO - 2016-02-25 10:15:39,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 133992448 10:15:39 INFO - 2016-02-25 10:15:39,419 DEBUG : BROWSER_OUTPUT: 10:16:30 INFO - 2016-02-25 10:16:30,451 DEBUG : BROWSER_OUTPUT: 1456424190443 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] 10:18:38 INFO - 2016-02-25 10:18:38,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46033/tests/dromaeo/cssquery-ext.html) 10:18:39 INFO - 2016-02-25 10:18:39,699 DEBUG : BROWSER_OUTPUT: RSS: Main: 211288064 10:18:39 INFO - 2016-02-25 10:18:39,699 DEBUG : BROWSER_OUTPUT: 10:21:41 INFO - 2016-02-25 10:21:41,706 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-ext.html (next: http://localhost:46033/tests/dromaeo/cssquery-jquery.html) 10:21:42 INFO - 2016-02-25 10:21:42,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 210886656 10:21:42 INFO - 2016-02-25 10:21:42,272 DEBUG : BROWSER_OUTPUT: 10:24:44 INFO - 2016-02-25 10:24:44,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46033/tests/dromaeo/cssquery-mootools.html) 10:24:44 INFO - 2016-02-25 10:24:44,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 201879552 10:24:44 INFO - 2016-02-25 10:24:44,576 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-02-25 10:27:46,270 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46033/tests/dromaeo/cssquery-prototype.html) 10:27:46 INFO - 2016-02-25 10:27:46,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 197394432 10:27:46 INFO - 2016-02-25 10:27:46,844 DEBUG : BROWSER_OUTPUT: 10:30:49 INFO - 2016-02-25 10:30:49,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46033/tests/dromaeo/cssquery-yui.html) 10:30:49 INFO - 2016-02-25 10:30:49,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 197156864 10:30:49 INFO - 2016-02-25 10:30:49,865 DEBUG : BROWSER_OUTPUT: 10:33:51 INFO - 2016-02-25 10:33:51,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/dromaeo/cssquery-yui.html (next: http://localhost:46033/tests/dromaeo/cssquery-dojo.html) 10:33:52 INFO - 2016-02-25 10:33:52,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 239697920 10:33:52 INFO - 2016-02-25 10:33:52,201 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,203 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:33:52 INFO - 2016-02-25 10:33:52,203 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:33:52 INFO - 2016-02-25 10:33:52,203 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:33:52 INFO - 2016-02-25 10:33:52,203 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:33:52 INFO - 2016-02-25 10:33:52,207 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3334.4164771165047;3403.0981789825687;3434.8626054957786;3448.293099914518;3461.3942560051983;5544.417836127209;5554.805581804638;5578.525825304854;5580.609357344987;5587.972060139698;5594.468525490082;5600.6079574429805;5600.887982240352;5603.439656034395;5624.240727501786;4824.940000099778;5068.1384164692;5087.5421212090905;5138.152204886189;5207.323048003755;5005.974970125144;5042.243663450494;5050.217216331474;5055.747212639371;5056.873578160538;4991.950080499203;5074.5432911038;5096.898062038757;5097.745112744366;5121.692698438105;5042.218456139304;5099.745012749385;5103.846884593431;5108.897822043576;5109.182530795092;5023.974880125613;5109.923351149736;5114.309568208281;5120.052790233782;5132.101882170605;5128.9230661540105;5241.0828105081455;5253.842384728464;5360.892782144335;5381.730913454312;5989;6013.308469525973;6064.514838812885;6066.120412540157;6072.271327440735;6047.092936059582;6077.361877002895;6083.30042045166;6088.056351265516;6093.451589356935;5346.117890548094;5506.972465137689;5668.716564171817;5793.362730099686;5798;6152.16945712332;6160.199174107337;6165.876682466325;6165.938340616626;6186.4741496973265;5692.544596432275;6014.8195554133445;6020.939790602126;6021.157038014681;6042.758289668364;5839.240898683144;5910.113482977502;6005.6396616203165;6023.427774361517;6035.637861728311;5937.9406205937385;5989.970050149721;5991.400859913973;5999.280086389661;6043.153958445821;5925.259342582177;5984.910226346608;5993.460588546965;6001.699915004232;6001.789937352171;4442.089371678798;4465.330200469891;4555.931661025087;4588.908221835544;4615;4133.545310015896;4147.502299724052;4148.0666849959125;4177.101923086551;4183.6653067754505;5593.776248950077;5832.387599302012;5835.44563266489;5837.270341207349;5841.7371218295275;5877.147813567009;6073.453389194905;6142.508599312045;6163.568550201443;6178.351273116258;5886.117082437583;5889.263842019747;5890.558208134407;5890.9410905891245;5895.7052147393315;5151.8196863109615;5291.497307755757;5321.467853214725;5341.839744807662;5344.198370244417;6119.681301915568;6229.470495007885;6232.3144454110015;6235.220597425322;6236.812895613139;5960.4635582797755;6040.788572399945;6045.093236014457;6049.818505444844;6094.146819445193;6090.665013424127;6138.662373569497;6178.62928224308;6193.194884664965;6256.061590761422;5971.671558064349;5978.402159784161;6012.368701286302;6020.30766461845;6028.758849645965;6077.270727512727;6083.574149809471;6084.087386891824;6091.7563297467605;6094.939050609437;5698.8290351289525;5801.129830525286;5832.766689332379;5914.911276330772;5930.229070221017;5881.5588830838715;5886.117082437669;5888.057910734262;5891.734871930859;5929.673867937305;5794.101914203306;5794.507466865363;5794.797182098607;5796.681182535001;5797.217375654402;5724.284464441945;5815.2149459822385;5924.911126333022;6046.395360463918;6083.178770865877;5772.913406298825;5785.218995435563;5785.681787501728;5787.218725472176;5789.2763404574425 10:33:52 INFO - 2016-02-25 10:33:52,207 DEBUG : BROWSER_OUTPUT: |1;ext.html;600.7056542294278;639.7504973060504;640.6060272932148;646.9223693156821;647.1296106736439;31536.68463315366;41030.79484602577;41385.79307103464;41413.17173656525;41427.17145657085;45517.089658206896;46102.30846537305;46110.53889461105;46115;46120.538794612046;7782.143964163965;7888.2506161914525;8940.687075952326;9117.635294588208;10226.488675566192;10605.734856628607;10681.519331630096;10711.892881071206;10741.785164296709;10754.677359679223;10664.520096595634;10735.087517561016;10769.83845242322;10770.946145269263;10772.08437282832;10594.576216951313;10687.358758474478;10693.198010149192;10693.786124277549;10696.251262411633;10494.527746251437;10638.946805265925;10640.521176547074;10645.520951557199;10646.414447205401;41010.38484422736;41458.58541414607;41579.792101039304;41618.58381416208;41696;3623.1847834237087;3737.8878633641034;3747.4940882980995;3924.489816323888;4089.6114869087687;31671.524927126113;31769.364612707617;31979.360412791615;31992.04023879264;32188.356232875445;4235.9364609530885;4243.469566304212;4294.355846623001;4304.7632380219075;4326.307790753464;297561;298500;298614;300568.4914726281;300578;60306.39693603096;62273.37726622766;62750;62803;62860.37139628545;54363;54785.72607136939;54929.72535137299;72297.6385118071;72506.63746681233;16167.353305867633;16258.918705406397;16309.266083026292;16391.262393192334;16417.67164656724;12579.05657075723;12673.239605623692;12678.302693351954;12715.872841271654;12719.809202861965;20570.38288851337;20636.79363206379;20688.896555517127;20781.376558703265;20848.37454876356;7620.390368770485;7751.379889608817;7787.4548781584745;7866.488678235896;7875.97612310396;874.5408660453313;1109.5284504085732;1110.9279545238883;1115;1227.5274019502376;28562;29018.07572018124;29051;29702.55446168266;30043;994.6071301835734;1036.8030074285862;1095.3427943233996;1147.730283383421;1172.384498138467;12694.365281735876;15540.222988850512;15582;15713.68572628541;16321.836781632031;338643;342090;342204;343620;344640;46185.76907115443;46921;55478.16782748262;55562.72218638881;55670.443295566525;1430.3777856632398;1445.0318286747904;1449.2101804516565;1449.6665766873678;1450.57933199371;26761.19716408433;27054.106212564933;29021.274468138465;29101;29302.70697293085;45002.54997449984;45519;45620;45672.77163614161;45679.314810278534;263236;265760;265817;266397;267503;34545.13637159091;34686.30627387387;34986.82506587451;35181.29637407289;35418.29163416666;45018.09963800771;45040.324395133444;45618;45863.54136458726;46396;38253.61746382501;38721.41917871178;39131.41302880402;39676.80161599174;39780.20439591134;36724.44913326249;36863.81568092143;37578.43632345572;37801.243975120895;38280;34471.138221544665;34589.48115778316;35230.295394092485;35242.47136292906;35327;29697;30550.847245763627;30768.230794230323;31085.844570777906;32042.51936220912;25999;26090;26225;26287.73712262853;27448.313792155357 10:33:52 INFO - 2016-02-25 10:33:52,207 DEBUG : BROWSER_OUTPUT: |2;jquery.html;392.35457672129365;393.06842782605213;393.21161072050523;393.4943597477242;394.8578511735773;14595.270236488159;17075.91462042688;17139.571510712223;18383.44849654511;18401.987890666023;12101.939490302559;12462.937685311583;12484.126111172221;18114.456566303;18426.44720658379;5037.269595908591;5140.306058682087;5148.613853960949;5151.072806894757;5159.509846564571;3959.9802000989953;4057.168280502505;4063.2686116499167;5179.585633149339;5567.3040869891265;5692.0608099663505;5733.512651424634;5737.454941780524;5741.454561816641;5741.483266506014;5695.202671625976;6122.632642041492;6129.080637904305;6129.509639228851;6131.693415329216;5479.4520547945285;5494.1758736189495;5494.532964698005;5496.642718223303;5503.3395992481155;13183.472661093545;13458.93270533641;13581.185128892299;13593.660158496017;14743.70512589753;2137.625915464803;2194.7585765565773;2247.898844551999;2292.839501234914;2311.214187176368;10117.645882394154;10119.392836429837;10492.213084018653;10901.727456813644;14070.22613756243;1848.778146622414;1871.4853415310763;1887.2451019592136;1923.740295060163;1957.862949593529;59050;59808;59956.400435995514;64851.67574162099;67927.32072679308;10683.252172348017;10961.56153753841;11030.28303160292;11069.723256918642;11376.658700239006;15114.622134446727;15183.848161518465;15185.544433667008;15197.088174709554;15199.468018619516;8151.266386025286;8157;8236.135205803424;8325.417220794487;8357.247847693738;7346.11846578414;7370.152432470228;7385.519941203805;7504.174540800507;7643.350315223157;4925.285833554186;4929.088118698052;4935;4946.208606622923;4963.429205641325;1722.6561040319832;1796.6316905034698;1904.9809501904804;1938.118156238936;1969.8030196980474;915.6337465014047;957.7366224288377;1022.6778564752079;1038.127972503101;1044.7544826965657;15007.474738384104;16666;17211.91394043022;17692.911535442498;18290.81709182891;916.7433118726767;939.6945992552447;950.4297421547017;970.5292932927524;996.1532697207316;9605.807883842284;9760.219182465386;9824.950875245579;9883.703488895344;10045.296829221885;503137;504823;505472;584450;681819;45887.77056114698;73750.63124684342;75916;76575.61712191404;77698;545.7080461952836;559.5104283751717;560.3276068717555;561.0518224201169;561.6012631031931;11203.103751700008;11322.490487927898;11525.077993760311;11702.941485292518;11791.764164716486;37924;37997;38388.232235354575;38958;39399.21201575895;50709;50740.746296268284;50831.237531436316;51196.74401627968;54735.45264547304;10500.894991050298;10619.097376723064;10631.255812093079;10728.088112510524;13283.136596121221;14989.625259368602;19430.708539372205;19774.90112549428;19830.900845496257;20483.692744608546;16086.195690215676;16097.114658693416;16373.345066197218;16727.581810454834;16798.832011680217;15840.28718707684;15918.937805730537;16392.590185245463;16450.424235151713;17146.828531714524;10781.137508999418;10946.671599852018;11039.724006899893;11087;11161.441927903736;15519.224038797789;15533.223338833239;15595.688086238439;16230.594235144215;16283.267252973881;16375.263113159697;16384.590385239986;16402.26189821437;16559.25483353228;16643.25105370237 10:33:52 INFO - 2016-02-25 10:33:52,208 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1524.8703860171886;1600.3598560575776;1605.462170172992;1737.5482374582612;1883.18081634489;7972.84054318914;8206.138355472678;8212.671493140275;8221.671133154667;8230.958845205765;7114.466415018881;7361.889571656429;7936.325412339958;8238.217369349903;8379.790505237372;2386.701662292218;2393.892274847636;2417.8186636002283;2418.891149898259;2434.85390876548;2059.042545216474;2421.092090466075;2466.654668346433;2469.2221950085664;2470.6047032474808;2053.5687505623764;2304.389336825734;2353.705786776653;2358.151065616377;2369.7748713872156;2297.4026753044163;2298.0577963035157;2306.9077236910503;2331.918382856609;2346.636271377939;2412.8311018228733;2418.7823095921453;2420.576399130163;2435.232901635979;2477.5044991001873;8263.297619702333;8378.581070946428;8383.95808020962;8390.160983901622;8390.496570205807;5059.772310246048;5168.9741551292;5178.14560597501;5178.663386879878;5182.378114626269;3282.130235487574;3292.308615190813;3322.2192784695576;3331.583552055993;3375.2236985493473;1132.456420917964;1166.1894982986828;1170.2159553099443;1198;1205.3370646144585;11821.94089029558;11824.645260642195;11832.763344733143;11834.230774999598;11843.111766617538;4535.297028960516;4584.037352156018;4601.12578610063;4601.72389656615;4634.559716826963;10091.293609447415;10091.394516329045;10095.141912937483;10107.241956853266;10125.088742013255;5106.361704786902;5108.310378098983;5117.104506711311;5117.104506711311;5124.48755124492;4669.883252918637;4672.6261899047995;4675.4389473262745;4676.602488788491;4681.976590117096;3958.7228893977144;4029.5970402960056;4048.7570745755347;4055.148418832019;4091.263572556909;1137.8350139229765;1173.3605185174101;1226.589092653953;1263.6082814327588;1265.9556915507912;1130.4630300607146;1133.739239974794;1177.8174382970653;1366.8906487480979;1427.0296198585063;7642.235776422424;7647.235276472308;7670.309672129535;7928.127905930343;8001.279884810395;1195.5875223048035;1196.084994978842;1197.3354788092518;1197.9700507487207;1199.208522375228;6181.629102253879;6194.0708893666315;6278.183836101278;6280.905786413207;6288.717007734663;9815.901840981642;10111.393316400892;10159.796804063877;10270.33242839213;10324.948375258225;9941.701748947544;10000.799984000425;10023.548940297704;10174.287799854088;10178.898211017944;7896.763097107096;7918.762437126896;7984.24149705777;7988.041435027834;7991.400644951652;7269.273072692688;7300.45246606496;7305;7323.304286092813;7585.431092668182;8816.867746983671;8951.104889510996;8971.730848074569;9060.728178154666;9145.222656074307;9183.954080229556;9200.447973121634;9295.953520232355;9389.718308450758;9417.95291023568;7348.816279593053;7422.96288518554;7429.7399591014055;7430.331270185709;7441.55350678961;7794.064712234568;7980.920190798018;8145.51126932386;8151.225633564802;8167.836643266983;6252.437280644764;7297.598632075287;7453.552786832807;7740.303372696484;7781.494202876795;6865.313468653095;7671.616419178907;7704.92295077042;7723.420743444377;7748.457607967388;7747.225277472433;7783.571903545133;7800.687972481037;7802.414818888493;7808.6095695216145;7082.787516374517;7166.426685865014;7190.388816950618;7206.171290301473;7213.6032518212005;7164.534305270141;7185.784426467214;7196.928030719626;7202;7205.351518363373 10:33:52 INFO - 2016-02-25 10:33:52,209 DEBUG : BROWSER_OUTPUT: |4;prototype.html;380.07262280036707;380.2964515646055;387.02276751203226;387.1965671232193;388.39022734307116;6042.486388656964;6063.6058656187315;6135.079738039296;6146.416090471404;6163.784267550636;5811.622244554101;5854.29748430189;5983.252093488314;6048.153258543796;6062.242219722535;4707.882302942437;4728.4798672146035;4737.597304229127;4743.146233677945;4751.667383283171;4515.05183911379;4745.810167593292;4749.810007599692;4754.405699287589;4763.452202996665;4582.381378513891;4637.675362724611;4730.858074257778;4745.477997420281;4750.4299484061785;4702.200625893589;4725.621950244006;4726.527347265281;4730.550597693214;4746.738929358884;4236.428082208893;4242.703010789246;4243.490781106256;4246.150769846012;4251.532331443539;5690.203371527948;5751.856203594902;5765.423457654243;5783.21926539916;5787.565932555033;5593.916091258634;5598.524125449301;5604;5610.4670056344585;5614.606977511576;6502.60984340941;6503.219613646393;6513.185851768529;6515.153030106103;6518.543701940866;4738.64460165489;4746.21687421575;4747.95252047482;4748.691335063209;4754.334393184957;6581.901271480931;6727.39453449187;6773.762918297836;6778.932210677929;6783.219929708097;4733.502982186889;4769.379980602499;4771.594414474808;4772.546608072227;4778.546038126373;4833.95166048335;4836.46798852126;4838.080764654775;4840.636952228527;4846.95153048465;5385.946140538545;5511.944880551143;5516.696581688046;5522.530584900329;5529.502344788908;6554.8361290968105;6557.278699343068;6557.967210163919;6558.147440832661;6560.147180866552;1677.622534929631;1691.6109294862251;1700.192408605927;1702.2680247493697;1702.7871516060493;1139.8632164140356;1140.9771804563861;1152.5274637398627;1153.81538953767;1181.8936295733254;6395.584287021329;6406.743730250738;6407.711652975628;6408.295087540367;6415.101885736;6490.837729056811;6710.966445167743;6720.596764194164;6722.831929201809;6723.260441351447;6292.05619157121;6340.175777148941;6462.644554549545;6464.870702585922;6467.708953097122;3611.6749492545796;3659.634036596319;3660.5790334111384;3661.8901432957055;3664.5419322584676;6928.930710692828;7097.148342198969;7132.893006604905;7137.571745695171;7144.3212894774915;6871.4502839772695;6963.895541566881;6972.500912303778;6975.337342952412;6978.127734033246;7156.069710937545;7156.999999999895;7170.641467926583;7170.892436613455;7171.426285897116;6624.238212605612;6795.932040679728;6799.11611490503;6804.285550017375;6806.081179040964;6723.562968407038;6724.092247546519;6725.293844146294;6730.125083739278;6732.865342693217;7141.071660684078;7154.463415243774;7155.499115061895;7156.105486814148;7157.176924653523;4582.08358328329;4655.883602909955;4756.500567440369;4803.567678908915;4808.158572249772;6679.4656427484715;6731.730730770714;6731.831704207434;6733.595984240961;6735;6570.244421891352;6578.934210657832;6583.177102862142;6584.967075164594;6590.8352291193105;6407.9038814418755;6539.215294164731;6544.934550654623;6553.246376666553;6556.606603603799;6439.130717353098;6445.774397896051;6446.5165112615805;6449.774257900951;6450.612963222222;6566.737330506726;6571.080048793268;6571.638559879252;6576.868462630816;6585.934140658532;6161.815145545641;6288.088227207121;6356.300806911328;6374.29882712892;6387.169667943138 10:33:52 INFO - 2016-02-25 10:33:52,210 DEBUG : BROWSER_OUTPUT: |5;yui.html;805.9597020148991;838.547184520359;886.716250799744;939.2579861909093;970.441995852385;13586.184828910273;13825.585232443043;13833.723325533496;13855.168689878614;13860.653483662914;11998.580049698263;12308.261504309748;13904.165750055005;13919.791203131936;13941.721165576682;739.7263012685314;751.8909758085074;874.9343799215069;889.1952782731638;889.7642124836891;885.4864178776327;890.7461373508552;898.0256421782374;898.1153563739712;898.4564338575133;912.5209265135791;913.3287034030016;917.5733284022928;918.6049998500642;918.7886786039215;757.6060448566742;763.5762152005634;763.5914785589683;913.392593925039;914.419343716738;686.543448606676;690.4786885901162;692.2454524568245;692.5117791956659;692.840646651273;11981.64055078349;12073.577424790177;12116.454759535842;12121;12128.332941688204;11791.882081179165;12091.758164836654;12570.182938108994;12627.368631568384;13242.20546767197;10355.223358248162;10390.324628899098;10743.946280268627;10828.566857325697;11024.393658348868;3725.8509659613583;3785.280796648628;3817.675497582709;3831.6934645228594;3835.9041023974346;50507.74746126246;50627.493725062646;53361.1995820063;53438;53720;10043.799124017478;10061.34601250931;10070.244731645156;10189.54147063384;10206.744831379276;18227;18278.360257390927;18285.817141828677;18289.634207316045;18308.084595770426;7221.530600511055;7255.383292420098;7328.267173282629;7456.925430745623;7512.812179695442;4404.757738324423;4518.096380723869;4538.659600529907;4545.5908968193025;4562.224421848294;3216.565763621928;3225.483922572377;3239.49787782894;3243.1243564237993;3247.0096620530494;574.4915749561585;581.1138014527879;581.6800759582213;585.689584520205;603.0923460192382;631.009315374857;654.8166513376262;662.102850637389;670.1957650819037;719.8524302517972;8311.6675332986;8443.704470343508;9018.368714190075;9065;9137.40606860552;461.9388736699759;586.2759492027343;678.3250665587673;719.7049209823947;740.2412527159704;4026.4966879140106;4271.145770845843;4383.539728328544;4427.889302767457;4501.032278060167;61333;61436;62075;62177.37822621806;62276.688616556625;51986.48013519892;56555;57418.425815741306;57568.42431575715;57727.134092988636;47504;48729;49078.754606227456;50524;51223.48776512262;8387;8540.188682075193;8631.56842157902;8802.38383313162;9014.68448604322;37207.25585488221;37591.43612845864;39013.804930975166;39016.41475377815;39051.63319283811;47618.52381476141;47930.760346199444;48187.277190841465;48447;48718.26922596235;10621.787564248827;10734.892651073389;11049.613263535737;11106.61126860556;11176.55293788272;12234.265944043385;12497.87502124967;12558.497660093859;12722.300273484678;13146.539871104465;10909.454527273447;11108.222424430212;11260.94369528147;11385.146114041645;11561.710957226136;11423.485943132413;11429.428528573704;11520;11622.70943226426;11649.767004660029;9111.380049202297;9312.627494900398;10722.410267435365;11264.887351126385;11517.82723259135;9285;9852.211823053995;9985.750356241153;10068.144207742424;10098.545565449425;8915.955420223117;9078.682246121354;9219.861702074608;9225.58484868196;9437.245020398213 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,859 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425232202__endTimestamp 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:33:52 INFO - 2016-02-25 10:33:52,211 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,212 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5591.78 Median:5832.77 stddev:629.22 (10.8%) stddev-sans-first:605.72 10:33:52 INFO - 2016-02-25 10:33:52,212 DEBUG : BROWSER_OUTPUT: Values: 3334.4 3403.1 3434.9 3448.3 3461.4 5544.4 5554.8 5578.5 5580.6 5588.0 5594.5 5600.6 5600.9 5603.4 5624.2 4824.9 5068.1 5087.5 5138.2 5207.3 5006.0 5042.2 5050.2 5055.7 5056.9 4992.0 5074.5 5096.9 5097.7 5121.7 5042.2 5099.7 5103.8 5108.9 5109.2 5024.0 5109.9 5114.3 5120.1 5132.1 5128.9 5241.1 5253.8 5360.9 5381.7 5989.0 6013.3 6064.5 6066.1 6072.3 6047.1 6077.4 6083.3 6088.1 6093.5 5346.1 5507.0 5668.7 5793.4 5798.0 6152.2 6160.2 6165.9 6165.9 6186.5 5692.5 6014.8 6020.9 6021.2 6042.8 5839.2 5910.1 6005.6 6023.4 6035.6 5937.9 5990.0 5991.4 5999.3 6043.2 5925.3 5984.9 5993.5 6001.7 6001.8 4442.1 4465.3 4555.9 4588.9 4615.0 4133.5 4147.5 4148.1 4177.1 4183.7 5593.8 5832.4 5835.4 5837.3 5841.7 5877.1 6073.5 6142.5 6163.6 6178.4 5886.1 5889.3 5890.6 5890.9 5895.7 5151.8 5291.5 5321.5 5341.8 5344.2 6119.7 6229.5 6232.3 6235.2 6236.8 5960.5 6040.8 6045.1 6049.8 6094.1 6090.7 6138.7 6178.6 6193.2 6256.1 5971.7 5978.4 6012.4 6020.3 6028.8 6077.3 6083.6 6084.1 6091.8 6094.9 5698.8 5801.1 5832.8 5914.9 5930.2 5881.6 5886.1 5888.1 5891.7 5929.7 5794.1 5794.5 5794.8 5796.7 5797.2 5724.3 5815.2 5924.9 6046.4 6083.2 5772.9 5785.2 5785.7 5787.2 5789.3 10:33:52 INFO - 2016-02-25 10:33:52,212 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,212 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48074.87 Median:340366.50 stddev:79366.08 (23.3%) stddev-sans-first:79508.67 10:33:52 INFO - 2016-02-25 10:33:52,212 DEBUG : BROWSER_OUTPUT: Values: 600.7 639.8 640.6 646.9 647.1 31536.7 41030.8 41385.8 41413.2 41427.2 45517.1 46102.3 46110.5 46115.0 46120.5 7782.1 7888.3 8940.7 9117.6 10226.5 10605.7 10681.5 10711.9 10741.8 10754.7 10664.5 10735.1 10769.8 10770.9 10772.1 10594.6 10687.4 10693.2 10693.8 10696.3 10494.5 10638.9 10640.5 10645.5 10646.4 41010.4 41458.6 41579.8 41618.6 41696.0 3623.2 3737.9 3747.5 3924.5 4089.6 31671.5 31769.4 31979.4 31992.0 32188.4 4235.9 4243.5 4294.4 4304.8 4326.3 297561.0 298500.0 298614.0 300568.5 300578.0 60306.4 62273.4 62750.0 62803.0 62860.4 54363.0 54785.7 54929.7 72297.6 72506.6 16167.4 16258.9 16309.3 16391.3 16417.7 12579.1 12673.2 12678.3 12715.9 12719.8 20570.4 20636.8 20688.9 20781.4 20848.4 7620.4 7751.4 7787.5 7866.5 7876.0 874.5 1109.5 1110.9 1115.0 1227.5 28562.0 29018.1 29051.0 29702.6 30043.0 994.6 1036.8 1095.3 1147.7 1172.4 12694.4 15540.2 15582.0 15713.7 16321.8 338643.0 342090.0 342204.0 343620.0 344640.0 46185.8 46921.0 55478.2 55562.7 55670.4 1430.4 1445.0 1449.2 1449.7 1450.6 26761.2 27054.1 29021.3 29101.0 29302.7 45002.5 45519.0 45620.0 45672.8 45679.3 263236.0 265760.0 265817.0 266397.0 267503.0 34545.1 34686.3 34986.8 35181.3 35418.3 45018.1 45040.3 45618.0 45863.5 46396.0 38253.6 38721.4 39131.4 39676.8 39780.2 36724.4 36863.8 37578.4 37801.2 38280.0 34471.1 34589.5 35230.3 35242.5 35327.0 29697.0 30550.8 30768.2 31085.8 32042.5 25999.0 26090.0 26225.0 26287.7 27448.3 10:33:52 INFO - 2016-02-25 10:33:52,213 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,213 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29506.16 Median:2221.33 stddev:91453.55 (4117.1%) stddev-sans-first:91683.98 10:33:52 INFO - 2016-02-25 10:33:52,213 DEBUG : BROWSER_OUTPUT: Values: 392.4 393.1 393.2 393.5 394.9 14595.3 17075.9 17139.6 18383.4 18402.0 12101.9 12462.9 12484.1 18114.5 18426.4 5037.3 5140.3 5148.6 5151.1 5159.5 3960.0 4057.2 4063.3 5179.6 5567.3 5692.1 5733.5 5737.5 5741.5 5741.5 5695.2 6122.6 6129.1 6129.5 6131.7 5479.5 5494.2 5494.5 5496.6 5503.3 13183.5 13458.9 13581.2 13593.7 14743.7 2137.6 2194.8 2247.9 2292.8 2311.2 10117.6 10119.4 10492.2 10901.7 14070.2 1848.8 1871.5 1887.2 1923.7 1957.9 59050.0 59808.0 59956.4 64851.7 67927.3 10683.3 10961.6 11030.3 11069.7 11376.7 15114.6 15183.8 15185.5 15197.1 15199.5 8151.3 8157.0 8236.1 8325.4 8357.2 7346.1 7370.2 7385.5 7504.2 7643.4 4925.3 4929.1 4935.0 4946.2 4963.4 1722.7 1796.6 1905.0 1938.1 1969.8 915.6 957.7 1022.7 1038.1 1044.8 15007.5 16666.0 17211.9 17692.9 18290.8 916.7 939.7 950.4 970.5 996.2 9605.8 9760.2 9825.0 9883.7 10045.3 503137.0 504823.0 505472.0 584450.0 681819.0 45887.8 73750.6 75916.0 76575.6 77698.0 545.7 559.5 560.3 561.1 561.6 11203.1 11322.5 11525.1 11702.9 11791.8 37924.0 37997.0 38388.2 38958.0 39399.2 50709.0 50740.7 50831.2 51196.7 54735.5 10500.9 10619.1 10631.3 10728.1 13283.1 14989.6 19430.7 19774.9 19830.9 20483.7 16086.2 16097.1 16373.3 16727.6 16798.8 15840.3 15918.9 16392.6 16450.4 17146.8 10781.1 10946.7 11039.7 11087.0 11161.4 15519.2 15533.2 15595.7 16230.6 16283.3 16375.3 16384.6 16402.3 16559.3 16643.3 10:33:52 INFO - 2016-02-25 10:33:52,213 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,213 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5882.59 Median:5112.71 stddev:3064.98 (59.9%) stddev-sans-first:3056.08 10:33:52 INFO - 2016-02-25 10:33:52,214 DEBUG : BROWSER_OUTPUT: Values: 1524.9 1600.4 1605.5 1737.5 1883.2 7972.8 8206.1 8212.7 8221.7 8231.0 7114.5 7361.9 7936.3 8238.2 8379.8 2386.7 2393.9 2417.8 2418.9 2434.9 2059.0 2421.1 2466.7 2469.2 2470.6 2053.6 2304.4 2353.7 2358.2 2369.8 2297.4 2298.1 2306.9 2331.9 2346.6 2412.8 2418.8 2420.6 2435.2 2477.5 8263.3 8378.6 8384.0 8390.2 8390.5 5059.8 5169.0 5178.1 5178.7 5182.4 3282.1 3292.3 3322.2 3331.6 3375.2 1132.5 1166.2 1170.2 1198.0 1205.3 11821.9 11824.6 11832.8 11834.2 11843.1 4535.3 4584.0 4601.1 4601.7 4634.6 10091.3 10091.4 10095.1 10107.2 10125.1 5106.4 5108.3 5117.1 5117.1 5124.5 4669.9 4672.6 4675.4 4676.6 4682.0 3958.7 4029.6 4048.8 4055.1 4091.3 1137.8 1173.4 1226.6 1263.6 1266.0 1130.5 1133.7 1177.8 1366.9 1427.0 7642.2 7647.2 7670.3 7928.1 8001.3 1195.6 1196.1 1197.3 1198.0 1199.2 6181.6 6194.1 6278.2 6280.9 6288.7 9815.9 10111.4 10159.8 10270.3 10324.9 9941.7 10000.8 10023.5 10174.3 10178.9 7896.8 7918.8 7984.2 7988.0 7991.4 7269.3 7300.5 7305.0 7323.3 7585.4 8816.9 8951.1 8971.7 9060.7 9145.2 9184.0 9200.4 9296.0 9389.7 9418.0 7348.8 7423.0 7429.7 7430.3 7441.6 7794.1 7980.9 8145.5 8151.2 8167.8 6252.4 7297.6 7453.6 7740.3 7781.5 6865.3 7671.6 7704.9 7723.4 7748.5 7747.2 7783.6 7800.7 7802.4 7808.6 7082.8 7166.4 7190.4 7206.2 7213.6 7164.5 7185.8 7196.9 7202.0 7205.4 10:33:52 INFO - 2016-02-25 10:33:52,214 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,214 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5507.38 Median:6225.94 stddev:1639.42 (26.3%) stddev-sans-first:1598.21 10:33:52 INFO - 2016-02-25 10:33:52,214 DEBUG : BROWSER_OUTPUT: Values: 380.1 380.3 387.0 387.2 388.4 6042.5 6063.6 6135.1 6146.4 6163.8 5811.6 5854.3 5983.3 6048.2 6062.2 4707.9 4728.5 4737.6 4743.1 4751.7 4515.1 4745.8 4749.8 4754.4 4763.5 4582.4 4637.7 4730.9 4745.5 4750.4 4702.2 4725.6 4726.5 4730.6 4746.7 4236.4 4242.7 4243.5 4246.2 4251.5 5690.2 5751.9 5765.4 5783.2 5787.6 5593.9 5598.5 5604.0 5610.5 5614.6 6502.6 6503.2 6513.2 6515.2 6518.5 4738.6 4746.2 4748.0 4748.7 4754.3 6581.9 6727.4 6773.8 6778.9 6783.2 4733.5 4769.4 4771.6 4772.5 4778.5 4834.0 4836.5 4838.1 4840.6 4847.0 5385.9 5511.9 5516.7 5522.5 5529.5 6554.8 6557.3 6558.0 6558.1 6560.1 1677.6 1691.6 1700.2 1702.3 1702.8 1139.9 1141.0 1152.5 1153.8 1181.9 6395.6 6406.7 6407.7 6408.3 6415.1 6490.8 6711.0 6720.6 6722.8 6723.3 6292.1 6340.2 6462.6 6464.9 6467.7 3611.7 3659.6 3660.6 3661.9 3664.5 6928.9 7097.1 7132.9 7137.6 7144.3 6871.5 6963.9 6972.5 6975.3 6978.1 7156.1 7157.0 7170.6 7170.9 7171.4 6624.2 6795.9 6799.1 6804.3 6806.1 6723.6 6724.1 6725.3 6730.1 6732.9 7141.1 7154.5 7155.5 7156.1 7157.2 4582.1 4655.9 4756.5 4803.6 4808.2 6679.5 6731.7 6731.8 6733.6 6735.0 6570.2 6578.9 6583.2 6585.0 6590.8 6407.9 6539.2 6544.9 6553.2 6556.6 6439.1 6445.8 6446.5 6449.8 6450.6 6566.7 6571.1 6571.6 6576.9 6585.9 6161.8 6288.1 6356.3 6374.3 6387.2 10:33:52 INFO - 2016-02-25 10:33:52,215 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,215 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14455.98 Median:48582.63 stddev:17348.16 (35.7%) stddev-sans-first:17366.54 10:33:52 INFO - 2016-02-25 10:33:52,216 DEBUG : BROWSER_OUTPUT: Values: 806.0 838.5 886.7 939.3 970.4 13586.2 13825.6 13833.7 13855.2 13860.7 11998.6 12308.3 13904.2 13919.8 13941.7 739.7 751.9 874.9 889.2 889.8 885.5 890.7 898.0 898.1 898.5 912.5 913.3 917.6 918.6 918.8 757.6 763.6 763.6 913.4 914.4 686.5 690.5 692.2 692.5 692.8 11981.6 12073.6 12116.5 12121.0 12128.3 11791.9 12091.8 12570.2 12627.4 13242.2 10355.2 10390.3 10743.9 10828.6 11024.4 3725.9 3785.3 3817.7 3831.7 3835.9 50507.7 50627.5 53361.2 53438.0 53720.0 10043.8 10061.3 10070.2 10189.5 10206.7 18227.0 18278.4 18285.8 18289.6 18308.1 7221.5 7255.4 7328.3 7456.9 7512.8 4404.8 4518.1 4538.7 4545.6 4562.2 3216.6 3225.5 3239.5 3243.1 3247.0 574.5 581.1 581.7 585.7 603.1 631.0 654.8 662.1 670.2 719.9 8311.7 8443.7 9018.4 9065.0 9137.4 461.9 586.3 678.3 719.7 740.2 4026.5 4271.1 4383.5 4427.9 4501.0 61333.0 61436.0 62075.0 62177.4 62276.7 51986.5 56555.0 57418.4 57568.4 57727.1 47504.0 48729.0 49078.8 50524.0 51223.5 8387.0 8540.2 8631.6 8802.4 9014.7 37207.3 37591.4 39013.8 39016.4 39051.6 47618.5 47930.8 48187.3 48447.0 48718.3 10621.8 10734.9 11049.6 11106.6 11176.6 12234.3 12497.9 12558.5 12722.3 13146.5 10909.5 11108.2 11260.9 11385.1 11561.7 11423.5 11429.4 11520.0 11622.7 11649.8 9111.4 9312.6 10722.4 11264.9 11517.8 9285.0 9852.2 9985.8 10068.1 10098.5 8916.0 9078.7 9219.9 9225.6 9437.2 10:33:52 INFO - 2016-02-25 10:33:52,216 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:33:52 INFO - 2016-02-25 10:33:52,216 DEBUG : BROWSER_OUTPUT: 10:33:52 INFO - 2016-02-25 10:33:52,745 INFO : Browser exited with error code: 0 10:33:52 INFO - 2016-02-25 10:33:52,764 INFO : Completed test dromaeo_css (00:18:29) 10:33:52 INFO - 2016-02-25 10:33:52,764 INFO : Starting test kraken 10:33:52 INFO - 2016-02-25 10:33:52,764 DEBUG : operating with platform_type : linux_ 10:33:52 INFO - 2016-02-25 10:33:52,764 INFO : Initialising browser for kraken test... 10:33:52 INFO - 2016-02-25 10:33:52,771 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp12hsfF/profile http://localhost:46033/getInfo.html 10:33:56 INFO - 2016-02-25 10:33:56,524 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:33:56 INFO - 2016-02-25 10:33:56,524 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:33:56 INFO - 2016-02-25 10:33:56,524 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:33:56 INFO - 2016-02-25 10:33:56,524 DEBUG : BROWSER_OUTPUT: __metrics 10:33:56 INFO - 2016-02-25 10:33:56,673 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:33:57 INFO - 2016-02-25 10:33:57,370 INFO : Browser initialized. 10:33:57 INFO - 2016-02-25 10:33:57,370 INFO : Running cycle 1/1 for kraken test... 10:33:57 INFO - 2016-02-25 10:33:57,371 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp12hsfF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:34:09 INFO - 2016-02-25 10:34:09,021 DEBUG : BROWSER_OUTPUT: RSS: Main: 132214784 10:34:09 INFO - 2016-02-25 10:34:09,021 DEBUG : BROWSER_OUTPUT: 10:34:11 INFO - 2016-02-25 10:34:11,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46033/tests/kraken/driver.html?testName=audio-beat-detection) 10:34:12 INFO - 2016-02-25 10:34:12,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 164630528 10:34:12 INFO - 2016-02-25 10:34:12,017 DEBUG : BROWSER_OUTPUT: 10:34:14 INFO - 2016-02-25 10:34:14,585 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46033/tests/kraken/driver.html?testName=audio-dft) 10:34:15 INFO - 2016-02-25 10:34:15,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 164241408 10:34:15 INFO - 2016-02-25 10:34:15,154 DEBUG : BROWSER_OUTPUT: 10:34:17 INFO - 2016-02-25 10:34:17,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46033/tests/kraken/driver.html?testName=audio-fft) 10:34:18 INFO - 2016-02-25 10:34:18,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 160878592 10:34:18 INFO - 2016-02-25 10:34:18,505 DEBUG : BROWSER_OUTPUT: 10:34:20 INFO - 2016-02-25 10:34:20,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46033/tests/kraken/driver.html?testName=audio-oscillator) 10:34:21 INFO - 2016-02-25 10:34:21,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 159907840 10:34:21 INFO - 2016-02-25 10:34:21,037 DEBUG : BROWSER_OUTPUT: 10:34:23 INFO - 2016-02-25 10:34:23,214 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46033/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:34:23 INFO - 2016-02-25 10:34:23,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 159481856 10:34:23 INFO - 2016-02-25 10:34:23,775 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-25 10:34:26,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46033/tests/kraken/driver.html?testName=imaging-darkroom) 10:34:27 INFO - 2016-02-25 10:34:27,219 DEBUG : BROWSER_OUTPUT: RSS: Main: 159252480 10:34:27 INFO - 2016-02-25 10:34:27,219 DEBUG : BROWSER_OUTPUT: 10:34:30 INFO - 2016-02-25 10:34:30,534 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46033/tests/kraken/driver.html?testName=imaging-desaturate) 10:34:31 INFO - 2016-02-25 10:34:31,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 160120832 10:34:31 INFO - 2016-02-25 10:34:31,100 DEBUG : BROWSER_OUTPUT: 10:34:33 INFO - 2016-02-25 10:34:33,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46033/tests/kraken/driver.html?testName=json-parse-financial) 10:34:34 INFO - 2016-02-25 10:34:34,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 160661504 10:34:34 INFO - 2016-02-25 10:34:34,379 DEBUG : BROWSER_OUTPUT: 10:34:35 INFO - 2016-02-25 10:34:35,948 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46033/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:34:36 INFO - 2016-02-25 10:34:36,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 167911424 10:34:36 INFO - 2016-02-25 10:34:36,510 DEBUG : BROWSER_OUTPUT: 10:34:38 INFO - 2016-02-25 10:34:38,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:34:38 INFO - 2016-02-25 10:34:38,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 165638144 10:34:38 INFO - 2016-02-25 10:34:38,846 DEBUG : BROWSER_OUTPUT: 10:34:40 INFO - 2016-02-25 10:34:40,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:34:41 INFO - 2016-02-25 10:34:41,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 172408832 10:34:41 INFO - 2016-02-25 10:34:41,381 DEBUG : BROWSER_OUTPUT: 10:34:43 INFO - 2016-02-25 10:34:43,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:34:44 INFO - 2016-02-25 10:34:44,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 176336896 10:34:44 INFO - 2016-02-25 10:34:44,289 DEBUG : BROWSER_OUTPUT: 10:34:46 INFO - 2016-02-25 10:34:46,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:34:47 INFO - 2016-02-25 10:34:47,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 175185920 10:34:47 INFO - 2016-02-25 10:34:47,358 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46033/tests/kraken/driver.html?testName=ai-astar) 10:34:49 INFO - 2016-02-25 10:34:49,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 233148416 10:34:49 INFO - 2016-02-25 10:34:49,256 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: |0;ai-astar;106;108;102;107;109;109;97;108;108;109 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;125;143;153;128;150;151;128;169;125 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;158;159;160;160;161;160;163;160;162 10:34:49 INFO - 2016-02-25 10:34:49,257 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;73;152;70;72;71;73;148;73 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;134;92;91;91;141;91;93;143 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;108;109;109;109;109 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;146;146;145;145;145;145;145 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;60;60;61;61;58;59;60;58;59 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;64;63;64;65;64;64;64 10:34:49 INFO - 2016-02-25 10:34:49,258 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;79;78;78;78;78;79;80;80 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;112;119;109;112;108;112;111;117;120;114 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;145;153;134;148;133;143;140;143;134 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;64;65;64;66;63;63;63;67;64 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,865 10:34:49 INFO - 2016-02-25 10:34:49,259 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:34:49 INFO - 2016-02-25 10:34:49,260 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425289254__endTimestamp 10:34:49 INFO - 2016-02-25 10:34:49,260 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,267 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:34:49 INFO - 2016-02-25 10:34:49,267 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.30 Median:108.50 stddev:3.89 (3.6%) stddev-sans-first:4.12 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: Values: 106.0 108.0 102.0 107.0 109.0 109.0 97.0 108.0 108.0 109.0 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.10 Median:149.50 stddev:14.96 (10.0%) stddev-sans-first:15.66 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: Values: 149.0 125.0 143.0 153.0 128.0 150.0 151.0 128.0 169.0 125.0 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.48 (0.9%) stddev-sans-first:1.50 10:34:49 INFO - 2016-02-25 10:34:49,268 DEBUG : BROWSER_OUTPUT: Values: 159.0 158.0 159.0 160.0 160.0 161.0 160.0 163.0 160.0 162.0 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.50 Median:72.00 stddev:32.97 (45.8%) stddev-sans-first:34.42 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 73.0 152.0 70.0 72.0 71.0 73.0 148.0 73.0 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:91.50 stddev:23.12 (25.3%) stddev-sans-first:23.96 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 134.0 92.0 91.0 91.0 141.0 91.0 93.0 143.0 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 108.0 109.0 109.0 109.0 109.0 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:34:49 INFO - 2016-02-25 10:34:49,269 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.50 Median:60.00 stddev:1.08 (1.8%) stddev-sans-first:1.13 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: Values: 59.0 60.0 60.0 61.0 61.0 58.0 59.0 60.0 58.0 59.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 64.0 63.0 64.0 65.0 64.0 64.0 64.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.50 stddev:1.40 (1.8%) stddev-sans-first:1.24 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 79.0 78.0 78.0 78.0 78.0 79.0 80.0 80.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.40 Median:113.00 stddev:4.06 (3.6%) stddev-sans-first:4.28 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: Values: 112.0 119.0 109.0 112.0 108.0 112.0 111.0 117.0 120.0 114.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.70 Median:143.00 stddev:6.90 (4.8%) stddev-sans-first:6.88 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: Values: 134.0 145.0 153.0 134.0 148.0 133.0 143.0 140.0 143.0 134.0 10:34:49 INFO - 2016-02-25 10:34:49,270 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,271 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.40 (2.2%) stddev-sans-first:1.41 10:34:49 INFO - 2016-02-25 10:34:49,271 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 65.0 64.0 66.0 63.0 63.0 63.0 67.0 64.0 10:34:49 INFO - 2016-02-25 10:34:49,271 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:34:49 INFO - 2016-02-25 10:34:49,271 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-25 10:34:49,889 INFO : Browser exited with error code: 0 10:34:49 INFO - 2016-02-25 10:34:49,940 INFO : Completed test kraken (00:00:57) 10:34:49 INFO - 2016-02-25 10:34:49,940 INFO : Starting test v8_7 10:34:49 INFO - 2016-02-25 10:34:49,940 DEBUG : operating with platform_type : linux_ 10:34:49 INFO - 2016-02-25 10:34:49,941 INFO : Initialising browser for v8_7 test... 10:34:49 INFO - 2016-02-25 10:34:49,947 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK7oR_n/profile http://localhost:46033/getInfo.html 10:34:53 INFO - 2016-02-25 10:34:53,635 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:34:53 INFO - 2016-02-25 10:34:53,635 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:34:53 INFO - 2016-02-25 10:34:53,635 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:34:53 INFO - 2016-02-25 10:34:53,635 DEBUG : BROWSER_OUTPUT: __metrics 10:34:53 INFO - 2016-02-25 10:34:53,784 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:34:54 INFO - 2016-02-25 10:34:54,573 INFO : Browser initialized. 10:34:54 INFO - 2016-02-25 10:34:54,574 INFO : Running cycle 1/1 for v8_7 test... 10:34:54 INFO - 2016-02-25 10:34:54,574 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpK7oR_n/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:35:06 INFO - 2016-02-25 10:35:06,254 DEBUG : BROWSER_OUTPUT: RSS: Main: 134250496 10:35:06 INFO - 2016-02-25 10:35:06,255 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46033/tests/v8_7/run.html (next: http://localhost:46033/tests/v8_7/run.html) 10:35:27 INFO - 2016-02-25 10:35:27,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 423796736 10:35:27 INFO - 2016-02-25 10:35:27,857 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,858 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:35:27 INFO - 2016-02-25 10:35:27,858 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |0;Richards;168.35016835016836 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.88046166529267 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.9321845469705 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 10:35:27 INFO - 2016-02-25 10:35:27,859 DEBUG : BROWSER_OUTPUT: |4;RayTrace;947.8672985781991 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: |5;Earley;538.5029617662897 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: |6;Boyer;10416.666666666666 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: |7;RegExp;26605.263157894737 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: |8;Splay;507.61421319796955 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:35:27 INFO - 2016-02-25 10:35:27,860 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425327857__endTimestamp 10:35:27 INFO - 2016-02-25 10:35:27,861 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,862 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:35:27 INFO - 2016-02-25 10:35:27,862 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:35:27 INFO - 2016-02-25 10:35:27,862 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,862 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.35 Median:168.35 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,862 DEBUG : BROWSER_OUTPUT: Values: 168.4 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.88 Median:164.88 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: Values: 164.9 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.93 Median:277.93 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: Values: 277.9 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,863 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: Values: 5133.3 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:947.87 Median:947.87 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: Values: 947.9 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:538.50 Median:538.50 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,864 DEBUG : BROWSER_OUTPUT: Values: 538.5 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10416.67 Median:10416.67 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: Values: 10416.7 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26605.26 Median:26605.26 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: Values: 26605.3 10:35:27 INFO - 2016-02-25 10:35:27,865 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:507.61 Median:507.61 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: Values: 507.6 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: Values: 4822.1 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:35:27 INFO - 2016-02-25 10:35:27,866 DEBUG : BROWSER_OUTPUT: 10:35:28 INFO - 2016-02-25 10:35:28,549 INFO : Browser exited with error code: 0 10:35:28 INFO - 2016-02-25 10:35:28,566 INFO : Completed test v8_7 (00:00:38) 10:35:28 INFO - 2016-02-25 10:35:28,900 INFO : Completed test suite (00:20:05) 10:35:28 INFO - 2016-02-25 10:35:28,900 DEBUG : Working with test: dromaeo_css 10:35:28 INFO - 2016-02-25 10:35:28,900 DEBUG : Generating results file: dromaeo_css 10:35:28 INFO - 2016-02-25 10:35:28,902 DEBUG : Working with test: kraken 10:35:28 INFO - 2016-02-25 10:35:28,902 DEBUG : Generating results file: kraken 10:35:28 INFO - 2016-02-25 10:35:28,903 INFO : javascript benchmark 10:35:28 INFO - 2016-02-25 10:35:28,903 DEBUG : Working with test: v8_7 10:35:28 INFO - 2016-02-25 10:35:28,903 DEBUG : Generating results file: v8_7 10:35:28 INFO - 2016-02-25 10:35:28,904 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:35:29 INFO - 2016-02-25 10:35:29,042 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:35:29 INFO - 2016-02-25 10:35:29,043 DEBUG : process_Request line: dromaeo_css 7313.04 graph.html#tests=[[72,53,35]] 10:35:29 INFO - 2016-02-25 10:35:29,043 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:35:29 INFO - 2016-02-25 10:35:29,141 DEBUG : process_Request line: kraken 1471.40 graph.html#tests=[[232,53,35]] 10:35:29 INFO - 2016-02-25 10:35:29,142 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:35:29 INFO - 2016-02-25 10:35:29,240 DEBUG : process_Request line: v8_7 23214.86 graph.html#tests=[[230,53,35]] 10:35:29 INFO - 2016-02-25 10:35:29,242 INFO : javascript benchmark 10:35:29 INFO - 2016-02-25 10:35:29,246 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3334.4164771165047, 3403.0981789825687, 3434.8626054957786, 3448.293099914518, 3461.3942560051983, 5544.417836127209, 5554.805581804638, 5578.525825304854, 5580.609357344987, 5587.972060139698, 5594.468525490082, 5600.6079574429805, 5600.887982240352, 5603.439656034395, 5624.240727501786, 4824.940000099778, 5068.1384164692, 5087.5421212090905, 5138.152204886189, 5207.323048003755, 5005.974970125144, 5042.243663450494, 5050.217216331474, 5055.747212639371, 5056.873578160538, 4991.950080499203, 5074.5432911038, 5096.898062038757, 5097.745112744366, 5121.692698438105, 5042.218456139304, 5099.745012749385, 5103.846884593431, 5108.897822043576, 5109.182530795092, 5023.974880125613, 5109.923351149736, 5114.309568208281, 5120.052790233782, 5132.101882170605, 5128.9230661540105, 5241.0828105081455, 5253.842384728464, 5360.892782144335, 5381.730913454312, 5989.0, 6013.308469525973, 6064.514838812885, 6066.120412540157, 6072.271327440735, 6047.092936059582, 6077.361877002895, 6083.30042045166, 6088.056351265516, 6093.451589356935, 5346.117890548094, 5506.972465137689, 5668.716564171817, 5793.362730099686, 5798.0, 6152.16945712332, 6160.199174107337, 6165.876682466325, 6165.938340616626, 6186.4741496973265, 5692.544596432275, 6014.8195554133445, 6020.939790602126, 6021.157038014681, 6042.758289668364, 5839.240898683144, 5910.113482977502, 6005.6396616203165, 6023.427774361517, 6035.637861728311, 5937.9406205937385, 5989.970050149721, 5991.400859913973, 5999.280086389661, 6043.153958445821, 5925.259342582177, 5984.910226346608, 5993.460588546965, 6001.699915004232, 6001.789937352171, 4442.089371678798, 4465.330200469891, 4555.931661025087, 4588.908221835544, 4615.0, 4133.545310015896, 4147.502299724052, 4148.0666849959125, 4177.101923086551, 4183.6653067754505, 5593.776248950077, 5832.387599302012, 5835.44563266489, 5837.270341207349, 5841.7371218295275, 5877.147813567009, 6073.453389194905, 6142.508599312045, 6163.568550201443, 6178.351273116258, 5886.117082437583, 5889.263842019747, 5890.558208134407, 5890.9410905891245, 5895.7052147393315, 5151.8196863109615, 5291.497307755757, 5321.467853214725, 5341.839744807662, 5344.198370244417, 6119.681301915568, 6229.470495007885, 6232.3144454110015, 6235.220597425322, 6236.812895613139, 5960.4635582797755, 6040.788572399945, 6045.093236014457, 6049.818505444844, 6094.146819445193, 6090.665013424127, 6138.662373569497, 6178.62928224308, 6193.194884664965, 6256.061590761422, 5971.671558064349, 5978.402159784161, 6012.368701286302, 6020.30766461845, 6028.758849645965, 6077.270727512727, 6083.574149809471, 6084.087386891824, 6091.7563297467605, 6094.939050609437, 5698.8290351289525, 5801.129830525286, 5832.766689332379, 5914.911276330772, 5930.229070221017, 5881.5588830838715, 5886.117082437669, 5888.057910734262, 5891.734871930859, 5929.673867937305, 5794.101914203306, 5794.507466865363, 5794.797182098607, 5796.681182535001, 5797.217375654402, 5724.284464441945, 5815.2149459822385, 5924.911126333022, 6046.395360463918, 6083.178770865877, 5772.913406298825, 5785.218995435563, 5785.681787501728, 5787.218725472176, 5789.2763404574425], "unit": "ms", "name": "dojo.html", "value": 5551.009332045341}, {"lowerIsBetter": false, "replicates": [600.7056542294278, 639.7504973060504, 640.6060272932148, 646.9223693156821, 647.1296106736439, 31536.68463315366, 41030.79484602577, 41385.79307103464, 41413.17173656525, 41427.17145657085, 45517.089658206896, 46102.30846537305, 46110.53889461105, 46115.0, 46120.538794612046, 7782.143964163965, 7888.2506161914525, 8940.687075952326, 9117.635294588208, 10226.488675566192, 10605.734856628607, 10681.519331630096, 10711.892881071206, 10741.785164296709, 10754.677359679223, 10664.520096595634, 10735.087517561016, 10769.83845242322, 10770.946145269263, 10772.08437282832, 10594.576216951313, 10687.358758474478, 10693.198010149192, 10693.786124277549, 10696.251262411633, 10494.527746251437, 10638.946805265925, 10640.521176547074, 10645.520951557199, 10646.414447205401, 41010.38484422736, 41458.58541414607, 41579.792101039304, 41618.58381416208, 41696.0, 3623.1847834237087, 3737.8878633641034, 3747.4940882980995, 3924.489816323888, 4089.6114869087687, 31671.524927126113, 31769.364612707617, 31979.360412791615, 31992.04023879264, 32188.356232875445, 4235.9364609530885, 4243.469566304212, 4294.355846623001, 4304.7632380219075, 4326.307790753464, 297561.0, 298500.0, 298614.0, 300568.4914726281, 300578.0, 60306.39693603096, 62273.37726622766, 62750.0, 62803.0, 62860.37139628545, 54363.0, 54785.72607136939, 54929.72535137299, 72297.6385118071, 72506.63746681233, 16167.353305867633, 16258.918705406397, 16309.266083026292, 16391.262393192334, 16417.67164656724, 12579.05657075723, 12673.239605623692, 12678.302693351954, 12715.872841271654, 12719.809202861965, 20570.38288851337, 20636.79363206379, 20688.896555517127, 20781.376558703265, 20848.37454876356, 7620.390368770485, 7751.379889608817, 7787.4548781584745, 7866.488678235896, 7875.97612310396, 874.5408660453313, 1109.5284504085732, 1110.9279545238883, 1115.0, 1227.5274019502376, 28562.0, 29018.07572018124, 29051.0, 29702.55446168266, 30043.0, 994.6071301835734, 1036.8030074285862, 1095.3427943233996, 1147.730283383421, 1172.384498138467, 12694.365281735876, 15540.222988850512, 15582.0, 15713.68572628541, 16321.836781632031, 338643.0, 342090.0, 342204.0, 343620.0, 344640.0, 46185.76907115443, 46921.0, 55478.16782748262, 55562.72218638881, 55670.443295566525, 1430.3777856632398, 1445.0318286747904, 1449.2101804516565, 1449.6665766873678, 1450.57933199371, 26761.19716408433, 27054.106212564933, 29021.274468138465, 29101.0, 29302.70697293085, 45002.54997449984, 45519.0, 45620.0, 45672.77163614161, 45679.314810278534, 263236.0, 265760.0, 265817.0, 266397.0, 267503.0, 34545.13637159091, 34686.30627387387, 34986.82506587451, 35181.29637407289, 35418.29163416666, 45018.09963800771, 45040.324395133444, 45618.0, 45863.54136458726, 46396.0, 38253.61746382501, 38721.41917871178, 39131.41302880402, 39676.80161599174, 39780.20439591134, 36724.44913326249, 36863.81568092143, 37578.43632345572, 37801.243975120895, 38280.0, 34471.138221544665, 34589.48115778316, 35230.295394092485, 35242.47136292906, 35327.0, 29697.0, 30550.847245763627, 30768.230794230323, 31085.844570777906, 32042.51936220912, 25999.0, 26090.0, 26225.0, 26287.73712262853, 27448.313792155357], "unit": "ms", "name": "ext.html", "value": 19607.116506085444}, {"lowerIsBetter": false, "replicates": [392.35457672129365, 393.06842782605213, 393.21161072050523, 393.4943597477242, 394.8578511735773, 14595.270236488159, 17075.91462042688, 17139.571510712223, 18383.44849654511, 18401.987890666023, 12101.939490302559, 12462.937685311583, 12484.126111172221, 18114.456566303, 18426.44720658379, 5037.269595908591, 5140.306058682087, 5148.613853960949, 5151.072806894757, 5159.509846564571, 3959.9802000989953, 4057.168280502505, 4063.2686116499167, 5179.585633149339, 5567.3040869891265, 5692.0608099663505, 5733.512651424634, 5737.454941780524, 5741.454561816641, 5741.483266506014, 5695.202671625976, 6122.632642041492, 6129.080637904305, 6129.509639228851, 6131.693415329216, 5479.4520547945285, 5494.1758736189495, 5494.532964698005, 5496.642718223303, 5503.3395992481155, 13183.472661093545, 13458.93270533641, 13581.185128892299, 13593.660158496017, 14743.70512589753, 2137.625915464803, 2194.7585765565773, 2247.898844551999, 2292.839501234914, 2311.214187176368, 10117.645882394154, 10119.392836429837, 10492.213084018653, 10901.727456813644, 14070.22613756243, 1848.778146622414, 1871.4853415310763, 1887.2451019592136, 1923.740295060163, 1957.862949593529, 59050.0, 59808.0, 59956.400435995514, 64851.67574162099, 67927.32072679308, 10683.252172348017, 10961.56153753841, 11030.28303160292, 11069.723256918642, 11376.658700239006, 15114.622134446727, 15183.848161518465, 15185.544433667008, 15197.088174709554, 15199.468018619516, 8151.266386025286, 8157.0, 8236.135205803424, 8325.417220794487, 8357.247847693738, 7346.11846578414, 7370.152432470228, 7385.519941203805, 7504.174540800507, 7643.350315223157, 4925.285833554186, 4929.088118698052, 4935.0, 4946.208606622923, 4963.429205641325, 1722.6561040319832, 1796.6316905034698, 1904.9809501904804, 1938.118156238936, 1969.8030196980474, 915.6337465014047, 957.7366224288377, 1022.6778564752079, 1038.127972503101, 1044.7544826965657, 15007.474738384104, 16666.0, 17211.91394043022, 17692.911535442498, 18290.81709182891, 916.7433118726767, 939.6945992552447, 950.4297421547017, 970.5292932927524, 996.1532697207316, 9605.807883842284, 9760.219182465386, 9824.950875245579, 9883.703488895344, 10045.296829221885, 503137.0, 504823.0, 505472.0, 584450.0, 681819.0, 45887.77056114698, 73750.63124684342, 75916.0, 76575.61712191404, 77698.0, 545.7080461952836, 559.5104283751717, 560.3276068717555, 561.0518224201169, 561.6012631031931, 11203.103751700008, 11322.490487927898, 11525.077993760311, 11702.941485292518, 11791.764164716486, 37924.0, 37997.0, 38388.232235354575, 38958.0, 39399.21201575895, 50709.0, 50740.746296268284, 50831.237531436316, 51196.74401627968, 54735.45264547304, 10500.894991050298, 10619.097376723064, 10631.255812093079, 10728.088112510524, 13283.136596121221, 14989.625259368602, 19430.708539372205, 19774.90112549428, 19830.900845496257, 20483.692744608546, 16086.195690215676, 16097.114658693416, 16373.345066197218, 16727.581810454834, 16798.832011680217, 15840.28718707684, 15918.937805730537, 16392.590185245463, 16450.424235151713, 17146.828531714524, 10781.137508999418, 10946.671599852018, 11039.724006899893, 11087.0, 11161.441927903736, 15519.224038797789, 15533.223338833239, 15595.688086238439, 16230.594235144215, 16283.267252973881, 16375.263113159697, 16384.590385239986, 16402.26189821437, 16559.25483353228, 16643.25105370237], "unit": "ms", "name": "jquery.html", "value": 8991.15880506872}, {"lowerIsBetter": false, "replicates": [1524.8703860171886, 1600.3598560575776, 1605.462170172992, 1737.5482374582612, 1883.18081634489, 7972.84054318914, 8206.138355472678, 8212.671493140275, 8221.671133154667, 8230.958845205765, 7114.466415018881, 7361.889571656429, 7936.325412339958, 8238.217369349903, 8379.790505237372, 2386.701662292218, 2393.892274847636, 2417.8186636002283, 2418.891149898259, 2434.85390876548, 2059.042545216474, 2421.092090466075, 2466.654668346433, 2469.2221950085664, 2470.6047032474808, 2053.5687505623764, 2304.389336825734, 2353.705786776653, 2358.151065616377, 2369.7748713872156, 2297.4026753044163, 2298.0577963035157, 2306.9077236910503, 2331.918382856609, 2346.636271377939, 2412.8311018228733, 2418.7823095921453, 2420.576399130163, 2435.232901635979, 2477.5044991001873, 8263.297619702333, 8378.581070946428, 8383.95808020962, 8390.160983901622, 8390.496570205807, 5059.772310246048, 5168.9741551292, 5178.14560597501, 5178.663386879878, 5182.378114626269, 3282.130235487574, 3292.308615190813, 3322.2192784695576, 3331.583552055993, 3375.2236985493473, 1132.456420917964, 1166.1894982986828, 1170.2159553099443, 1198.0, 1205.3370646144585, 11821.94089029558, 11824.645260642195, 11832.763344733143, 11834.230774999598, 11843.111766617538, 4535.297028960516, 4584.037352156018, 4601.12578610063, 4601.72389656615, 4634.559716826963, 10091.293609447415, 10091.394516329045, 10095.141912937483, 10107.241956853266, 10125.088742013255, 5106.361704786902, 5108.310378098983, 5117.104506711311, 5117.104506711311, 5124.48755124492, 4669.883252918637, 4672.6261899047995, 4675.4389473262745, 4676.602488788491, 4681.976590117096, 3958.7228893977144, 4029.5970402960056, 4048.7570745755347, 4055.148418832019, 4091.263572556909, 1137.8350139229765, 1173.3605185174101, 1226.589092653953, 1263.6082814327588, 1265.9556915507912, 1130.4630300607146, 1133.739239974794, 1177.8174382970653, 1366.8906487480979, 1427.0296198585063, 7642.235776422424, 7647.235276472308, 7670.309672129535, 7928.127905930343, 8001.279884810395, 1195.5875223048035, 1196.084994978842, 1197.3354788092518, 1197.9700507487207, 1199.208522375228, 6181.629102253879, 6194.0708893666315, 6278.183836101278, 6280.905786413207, 6288.717007734663, 9815.901840981642, 10111.393316400892, 10159.796804063877, 10270.33242839213, 10324.948375258225, 9941.701748947544, 10000.799984000425, 10023.548940297704, 10174.287799854088, 10178.898211017944, 7896.763097107096, 7918.762437126896, 7984.24149705777, 7988.041435027834, 7991.400644951652, 7269.273072692688, 7300.45246606496, 7305.0, 7323.304286092813, 7585.431092668182, 8816.867746983671, 8951.104889510996, 8971.730848074569, 9060.728178154666, 9145.222656074307, 9183.954080229556, 9200.447973121634, 9295.953520232355, 9389.718308450758, 9417.95291023568, 7348.816279593053, 7422.96288518554, 7429.7399591014055, 7430.331270185709, 7441.55350678961, 7794.064712234568, 7980.920190798018, 8145.51126932386, 8151.225633564802, 8167.836643266983, 6252.437280644764, 7297.598632075287, 7453.552786832807, 7740.303372696484, 7781.494202876795, 6865.313468653095, 7671.616419178907, 7704.92295077042, 7723.420743444377, 7748.457607967388, 7747.225277472433, 7783.571903545133, 7800.687972481037, 7802.414818888493, 7808.6095695216145, 7082.787516374517, 7166.426685865014, 7190.388816950618, 7206.171290301473, 7213.6032518212005, 7164.534305270141, 7185.784426467214, 7196.928030719626, 7202.0, 7205.351518363373], "unit": "ms", "name": "mootools.html", "value": 4826.222458140663}, {"lowerIsBetter": false, "replicates": [380.07262280036707, 380.2964515646055, 387.02276751203226, 387.1965671232193, 388.39022734307116, 6042.486388656964, 6063.6058656187315, 6135.079738039296, 6146.416090471404, 6163.784267550636, 5811.622244554101, 5854.29748430189, 5983.252093488314, 6048.153258543796, 6062.242219722535, 4707.882302942437, 4728.4798672146035, 4737.597304229127, 4743.146233677945, 4751.667383283171, 4515.05183911379, 4745.810167593292, 4749.810007599692, 4754.405699287589, 4763.452202996665, 4582.381378513891, 4637.675362724611, 4730.858074257778, 4745.477997420281, 4750.4299484061785, 4702.200625893589, 4725.621950244006, 4726.527347265281, 4730.550597693214, 4746.738929358884, 4236.428082208893, 4242.703010789246, 4243.490781106256, 4246.150769846012, 4251.532331443539, 5690.203371527948, 5751.856203594902, 5765.423457654243, 5783.21926539916, 5787.565932555033, 5593.916091258634, 5598.524125449301, 5604.0, 5610.4670056344585, 5614.606977511576, 6502.60984340941, 6503.219613646393, 6513.185851768529, 6515.153030106103, 6518.543701940866, 4738.64460165489, 4746.21687421575, 4747.95252047482, 4748.691335063209, 4754.334393184957, 6581.901271480931, 6727.39453449187, 6773.762918297836, 6778.932210677929, 6783.219929708097, 4733.502982186889, 4769.379980602499, 4771.594414474808, 4772.546608072227, 4778.546038126373, 4833.95166048335, 4836.46798852126, 4838.080764654775, 4840.636952228527, 4846.95153048465, 5385.946140538545, 5511.944880551143, 5516.696581688046, 5522.530584900329, 5529.502344788908, 6554.8361290968105, 6557.278699343068, 6557.967210163919, 6558.147440832661, 6560.147180866552, 1677.622534929631, 1691.6109294862251, 1700.192408605927, 1702.2680247493697, 1702.7871516060493, 1139.8632164140356, 1140.9771804563861, 1152.5274637398627, 1153.81538953767, 1181.8936295733254, 6395.584287021329, 6406.743730250738, 6407.711652975628, 6408.295087540367, 6415.101885736, 6490.837729056811, 6710.966445167743, 6720.596764194164, 6722.831929201809, 6723.260441351447, 6292.05619157121, 6340.175777148941, 6462.644554549545, 6464.870702585922, 6467.708953097122, 3611.6749492545796, 3659.634036596319, 3660.5790334111384, 3661.8901432957055, 3664.5419322584676, 6928.930710692828, 7097.148342198969, 7132.893006604905, 7137.571745695171, 7144.3212894774915, 6871.4502839772695, 6963.895541566881, 6972.500912303778, 6975.337342952412, 6978.127734033246, 7156.069710937545, 7156.999999999895, 7170.641467926583, 7170.892436613455, 7171.426285897116, 6624.238212605612, 6795.932040679728, 6799.11611490503, 6804.285550017375, 6806.081179040964, 6723.562968407038, 6724.092247546519, 6725.293844146294, 6730.125083739278, 6732.865342693217, 7141.071660684078, 7154.463415243774, 7155.499115061895, 7156.105486814148, 7157.176924653523, 4582.08358328329, 4655.883602909955, 4756.500567440369, 4803.567678908915, 4808.158572249772, 6679.4656427484715, 6731.730730770714, 6731.831704207434, 6733.595984240961, 6735.0, 6570.244421891352, 6578.934210657832, 6583.177102862142, 6584.967075164594, 6590.8352291193105, 6407.9038814418755, 6539.215294164731, 6544.934550654623, 6553.246376666553, 6556.606603603799, 6439.130717353098, 6445.774397896051, 6446.5165112615805, 6449.774257900951, 6450.612963222222, 6566.737330506726, 6571.080048793268, 6571.638559879252, 6576.868462630816, 6585.934140658532, 6161.815145545641, 6288.088227207121, 6356.300806911328, 6374.29882712892, 6387.169667943138], "unit": "ms", "name": "prototype.html", "value": 4988.728802254668}, {"lowerIsBetter": false, "replicates": [805.9597020148991, 838.547184520359, 886.716250799744, 939.2579861909093, 970.441995852385, 13586.184828910273, 13825.585232443043, 13833.723325533496, 13855.168689878614, 13860.653483662914, 11998.580049698263, 12308.261504309748, 13904.165750055005, 13919.791203131936, 13941.721165576682, 739.7263012685314, 751.8909758085074, 874.9343799215069, 889.1952782731638, 889.7642124836891, 885.4864178776327, 890.7461373508552, 898.0256421782374, 898.1153563739712, 898.4564338575133, 912.5209265135791, 913.3287034030016, 917.5733284022928, 918.6049998500642, 918.7886786039215, 757.6060448566742, 763.5762152005634, 763.5914785589683, 913.392593925039, 914.419343716738, 686.543448606676, 690.4786885901162, 692.2454524568245, 692.5117791956659, 692.840646651273, 11981.64055078349, 12073.577424790177, 12116.454759535842, 12121.0, 12128.332941688204, 11791.882081179165, 12091.758164836654, 12570.182938108994, 12627.368631568384, 13242.20546767197, 10355.223358248162, 10390.324628899098, 10743.946280268627, 10828.566857325697, 11024.393658348868, 3725.8509659613583, 3785.280796648628, 3817.675497582709, 3831.6934645228594, 3835.9041023974346, 50507.74746126246, 50627.493725062646, 53361.1995820063, 53438.0, 53720.0, 10043.799124017478, 10061.34601250931, 10070.244731645156, 10189.54147063384, 10206.744831379276, 18227.0, 18278.360257390927, 18285.817141828677, 18289.634207316045, 18308.084595770426, 7221.530600511055, 7255.383292420098, 7328.267173282629, 7456.925430745623, 7512.812179695442, 4404.757738324423, 4518.096380723869, 4538.659600529907, 4545.5908968193025, 4562.224421848294, 3216.565763621928, 3225.483922572377, 3239.49787782894, 3243.1243564237993, 3247.0096620530494, 574.4915749561585, 581.1138014527879, 581.6800759582213, 585.689584520205, 603.0923460192382, 631.009315374857, 654.8166513376262, 662.102850637389, 670.1957650819037, 719.8524302517972, 8311.6675332986, 8443.704470343508, 9018.368714190075, 9065.0, 9137.40606860552, 461.9388736699759, 586.2759492027343, 678.3250665587673, 719.7049209823947, 740.2412527159704, 4026.4966879140106, 4271.145770845843, 4383.539728328544, 4427.889302767457, 4501.032278060167, 61333.0, 61436.0, 62075.0, 62177.37822621806, 62276.688616556625, 51986.48013519892, 56555.0, 57418.425815741306, 57568.42431575715, 57727.134092988636, 47504.0, 48729.0, 49078.754606227456, 50524.0, 51223.48776512262, 8387.0, 8540.188682075193, 8631.56842157902, 8802.38383313162, 9014.68448604322, 37207.25585488221, 37591.43612845864, 39013.804930975166, 39016.41475377815, 39051.63319283811, 47618.52381476141, 47930.760346199444, 48187.277190841465, 48447.0, 48718.26922596235, 10621.787564248827, 10734.892651073389, 11049.613263535737, 11106.61126860556, 11176.55293788272, 12234.265944043385, 12497.87502124967, 12558.497660093859, 12722.300273484678, 13146.539871104465, 10909.454527273447, 11108.222424430212, 11260.94369528147, 11385.146114041645, 11561.710957226136, 11423.485943132413, 11429.428528573704, 11520.0, 11622.70943226426, 11649.767004660029, 9111.380049202297, 9312.627494900398, 10722.410267435365, 11264.887351126385, 11517.82723259135, 9285.0, 9852.211823053995, 9985.750356241153, 10068.144207742424, 10098.545565449425, 8915.955420223117, 9078.682246121354, 9219.861702074608, 9225.58484868196, 9437.245020398213], "unit": "ms", "name": "yui.html", "value": 6491.603639098963}], "name": "dromaeo_css", "value": 7313.037736128766}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [106.0, 108.0, 102.0, 107.0, 109.0, 109.0, 97.0, 108.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.3}, {"lowerIsBetter": true, "replicates": [149.0, 125.0, 143.0, 153.0, 128.0, 150.0, 151.0, 128.0, 169.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.1}, {"lowerIsBetter": true, "replicates": [159.0, 158.0, 159.0, 160.0, 160.0, 161.0, 160.0, 163.0, 160.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 73.0, 152.0, 70.0, 72.0, 71.0, 73.0, 148.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 87.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 134.0, 92.0, 91.0, 91.0, 141.0, 91.0, 93.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.9}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [59.0, 60.0, 60.0, 61.0, 61.0, 58.0, 59.0, 60.0, 58.0, 59.0], "unit": "ms", "name": "json-parse-financial", "value": 59.5}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 64.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.9}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 79.0, 78.0, 78.0, 78.0, 78.0, 79.0, 80.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.2}, {"lowerIsBetter": true, "replicates": [112.0, 119.0, 109.0, 112.0, 108.0, 112.0, 111.0, 117.0, 120.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.4}, {"lowerIsBetter": true, "replicates": [134.0, 145.0, 153.0, 134.0, 148.0, 133.0, 143.0, 140.0, 143.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.7}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 65.0, 64.0, 66.0, 63.0, 63.0, 63.0, 67.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1471.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.35016835016836], "unit": "score", "name": "Richards", "value": 209.69388000000006}, {"lowerIsBetter": false, "replicates": [164.88046166529267], "unit": "score", "name": "DeltaBlue", "value": 401.0056700000001}, {"lowerIsBetter": false, "replicates": [277.9321845469705], "unit": "score", "name": "Encrypt", "value": 957.7192379999997}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [947.8672985781991], "unit": "score", "name": "RayTrace", "value": 780.6883950000001}, {"lowerIsBetter": false, "replicates": [538.5029617662897], "unit": "score", "name": "Earley", "value": 1237.621791}, {"lowerIsBetter": false, "replicates": [10416.666666666666], "unit": "score", "name": "Boyer", "value": 63.980448000000024}, {"lowerIsBetter": false, "replicates": [26605.263157894737], "unit": "score", "name": "RegExp", "value": 34.24078140454997}, {"lowerIsBetter": false, "replicates": [507.61421319796955], "unit": "score", "name": "Splay", "value": 160.53727}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23214.856013132776}]} 10:35:29 INFO - RETURN: dromaeo_css: 7313.04 10:35:29 INFO - RETURN: kraken: 1471.40 10:35:29 INFO - RETURN: v8_7: 23214.86 10:35:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23214.86"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1471.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7313.04"}}} 10:35:29 INFO - Return code: 0 10:35:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:35:29 INFO - # TBPL SUCCESS # 10:35:29 INFO - Running post-action listener: _resource_record_post_action 10:35:29 INFO - Running post-run listener: _resource_record_post_run 10:35:30 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 4096; Write bytes: 364908544; Read time: 16; Write time: 241644 10:35:30 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 97787904; Read time: 0; Write time: 92892 10:35:30 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 4096; Write bytes: 266194944; Read time: 16; Write time: 148728 10:35:30 INFO - Running post-run listener: _upload_blobber_files 10:35:30 INFO - Blob upload gear active. 10:35:30 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:35:30 INFO - Copying logs to upload dir... 10:35:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.564890 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-25 10:35:30.433065) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:35:30.436628) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010079 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456415728/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:35:30.492442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:35:30.493315) ========= 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=ed14279e38b05397cf4aa391000001a4-1456423827.817700-1693253231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:35:30.547530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:35:30.548217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:35:30.548863) ========= ========= Total master_lag: 0.28 =========