builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1456420387.84 results: success (0) buildid: 20160225064930 builduid: 5fb7bd12874a4a7398f40b879ae17260 revision: b3bc3d627637 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.840346) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.840731) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.841005) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006758 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.864789) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.865053) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.878348) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.878609) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004676 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.930012) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.930287) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.930620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:07.930902) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 09:13:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 214M=0s 2016-02-25 09:13:08 (214 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162038 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.105368) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.105825) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019115 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.155056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.155411) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b3bc3d627637 --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 b3bc3d627637 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 09:13:08,350 Setting DEBUG logging. 2016-02-25 09:13:08,350 attempt 1/10 2016-02-25 09:13:08,350 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b3bc3d627637?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 09:13:08,624 unpacking tar archive at: mozilla-beta-b3bc3d627637/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663830 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.833255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.833605) ========= echo b3bc3d627637 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'b3bc3d627637'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False b3bc3d627637 program finished with exit code 0 elapsedTime=0.004247 script_repo_revision: 'b3bc3d627637' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.882950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.883267) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 09:13:08.893559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-25 09:13:08.893940) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:13:09 INFO - MultiFileLogger online at 20160225 09:13:09 in /builds/slave/test-pgo 09:13:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:13:09 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:13:09 INFO - {'append_to_log': False, 09:13:09 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:13:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:13:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:13:09 INFO - 'branch': 'Mozilla-Beta', 09:13:09 INFO - 'buildbot_json_path': 'buildprops.json', 09:13:09 INFO - 'config_files': ('talos/linux_config.py',), 09:13:09 INFO - 'default_actions': ('clobber', 09:13:09 INFO - 'read-buildbot-config', 09:13:09 INFO - 'download-and-extract', 09:13:09 INFO - 'populate-webroot', 09:13:09 INFO - 'create-virtualenv', 09:13:09 INFO - 'install', 09:13:09 INFO - 'run-tests'), 09:13:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:13:09 INFO - 'download_minidump_stackwalk': True, 09:13:09 INFO - 'download_symbols': 'ondemand', 09:13:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:13:09 INFO - 'tooltool.py': '/tools/tooltool.py', 09:13:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:13:09 INFO - '/tools/misc-python/virtualenv.py')}, 09:13:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:13:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:13:09 INFO - 'installer_path': 'installer.exe', 09:13:09 INFO - 'log_level': 'info', 09:13:09 INFO - 'log_name': 'talos', 09:13:09 INFO - 'log_to_console': True, 09:13:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:13:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:13:09 INFO - 'opt_config_files': (), 09:13:09 INFO - 'pip_index': False, 09:13:09 INFO - 'sps_profile': False, 09:13:09 INFO - 'sps_profile_interval': 0, 09:13:09 INFO - 'suite': 'dromaeojs', 09:13:09 INFO - 'system_bits': '32', 09:13:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:13:09 INFO - 'title': 'talos-linux64-ix-005', 09:13:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:13:09 INFO - 'use_talos_json': True, 09:13:09 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:13:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:13:09 INFO - 'work_dir': 'build'} 09:13:09 INFO - ##### 09:13:09 INFO - ##### Running clobber step. 09:13:09 INFO - ##### 09:13:09 INFO - Running pre-action listener: _resource_record_pre_action 09:13:09 INFO - Running main action method: clobber 09:13:09 INFO - rmtree: /builds/slave/test-pgo/build 09:13:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:13:10 INFO - Running post-action listener: _resource_record_post_action 09:13:10 INFO - ##### 09:13:10 INFO - ##### Running read-buildbot-config step. 09:13:10 INFO - ##### 09:13:10 INFO - Running pre-action listener: _resource_record_pre_action 09:13:10 INFO - Running main action method: read_buildbot_config 09:13:10 INFO - Using buildbot properties: 09:13:10 INFO - { 09:13:10 INFO - "properties": { 09:13:10 INFO - "buildnumber": 98, 09:13:10 INFO - "product": "firefox", 09:13:10 INFO - "script_repo_revision": "production", 09:13:10 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:13:10 INFO - "repository": "", 09:13:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:13:10 INFO - "buildid": "20160225064930", 09:13:10 INFO - "slavename": "talos-linux64-ix-005", 09:13:10 INFO - "pgo_build": "True", 09:13:10 INFO - "basedir": "/builds/slave/test-pgo", 09:13:10 INFO - "project": "", 09:13:10 INFO - "platform": "ubuntu64_hw", 09:13:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:13:10 INFO - "slavebuilddir": "test", 09:13:10 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:13:10 INFO - "branch": "mozilla-beta", 09:13:10 INFO - "repo_path": "releases/mozilla-beta", 09:13:10 INFO - "stage_platform": "linux64-pgo", 09:13:10 INFO - "builduid": "5fb7bd12874a4a7398f40b879ae17260", 09:13:10 INFO - "revision": "b3bc3d627637" 09:13:10 INFO - }, 09:13:10 INFO - "sourcestamp": { 09:13:10 INFO - "repository": "", 09:13:10 INFO - "hasPatch": false, 09:13:10 INFO - "project": "", 09:13:10 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:13:10 INFO - "changes": [ 09:13:10 INFO - { 09:13:10 INFO - "category": null, 09:13:10 INFO - "files": [ 09:13:10 INFO - { 09:13:10 INFO - "url": null, 09:13:10 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:13:10 INFO - } 09:13:10 INFO - ], 09:13:10 INFO - "repository": "", 09:13:10 INFO - "rev": "b3bc3d627637", 09:13:10 INFO - "who": "sendchange", 09:13:10 INFO - "when": 1456420359, 09:13:10 INFO - "number": 7653881, 09:13:10 INFO - "comments": "Bug 1208226 - Don_t crash when failing to map a segment of shared memory. r=sotaro, billm a=sylvestre", 09:13:10 INFO - "project": "", 09:13:10 INFO - "at": "Thu 25 Feb 2016 09:12:39", 09:13:10 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:13:10 INFO - "revlink": "", 09:13:10 INFO - "properties": [ 09:13:10 INFO - [ 09:13:10 INFO - "buildid", 09:13:10 INFO - "20160225064930", 09:13:10 INFO - "Change" 09:13:10 INFO - ], 09:13:10 INFO - [ 09:13:10 INFO - "builduid", 09:13:10 INFO - "5fb7bd12874a4a7398f40b879ae17260", 09:13:10 INFO - "Change" 09:13:10 INFO - ], 09:13:10 INFO - [ 09:13:10 INFO - "pgo_build", 09:13:10 INFO - "True", 09:13:10 INFO - "Change" 09:13:10 INFO - ] 09:13:10 INFO - ], 09:13:10 INFO - "revision": "b3bc3d627637" 09:13:10 INFO - } 09:13:10 INFO - ], 09:13:10 INFO - "revision": "b3bc3d627637" 09:13:10 INFO - } 09:13:10 INFO - } 09:13:10 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:13:10 INFO - Running post-action listener: _resource_record_post_action 09:13:10 INFO - ##### 09:13:10 INFO - ##### Running download-and-extract step. 09:13:10 INFO - ##### 09:13:10 INFO - Running pre-action listener: _resource_record_pre_action 09:13:10 INFO - Running main action method: download_and_extract 09:13:10 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/test_packages.json 09:13:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:13:10 INFO - Downloaded 1270 bytes. 09:13:10 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:13:10 INFO - Using the following test package requirements: 09:13:10 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:13:10 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:13:10 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'jsshell-linux-x86_64.zip'], 09:13:10 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:13:10 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:13:10 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:13:10 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:13:10 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:13:10 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:13:10 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:13:10 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:13:10 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:13:10 INFO - Downloaded 21584086 bytes. 09:13:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:13:10 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:10 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:13:11 INFO - Return code: 0 09:13:11 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:11 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:11 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:13:12 INFO - Downloaded 21584086 bytes. 09:13:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:13:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:13:12 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:13:14 INFO - Return code: 0 09:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:13:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:13:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:13:15 INFO - Downloaded 11132038 bytes. 09:13:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:13:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:13:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:13:16 INFO - Return code: 0 09:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:13:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:13:28 INFO - Downloaded 57936745 bytes. 09:13:28 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:13:28 INFO - mkdir: /builds/slave/test-pgo/properties 09:13:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:13:28 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:13:28 INFO - Contents: 09:13:28 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:13:28 INFO - Running post-action listener: _resource_record_post_action 09:13:28 INFO - Running post-action listener: set_extra_try_arguments 09:13:28 INFO - ##### 09:13:28 INFO - ##### Running populate-webroot step. 09:13:28 INFO - ##### 09:13:28 INFO - Running pre-action listener: _resource_record_pre_action 09:13:28 INFO - Running main action method: populate_webroot 09:13:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:28 INFO - '--tppagecycles', 09:13:28 INFO - '7'], 09:13:28 INFO - 'tests': ['tsvgm']}}, 09:13:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:13:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:13:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:13:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'tests': ['damp', 'tps']}, 09:13:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['damp', 'tps']}, 09:13:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:13:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:28 INFO - 'other': {'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'other_l64': {'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'other_nol64': {'tests': ['a11yr', 09:13:28 INFO - 'ts_paint', 09:13:28 INFO - 'tpaint', 09:13:28 INFO - 'sessionrestore', 09:13:28 INFO - 'sessionrestore_no_auto_restore']}, 09:13:28 INFO - 'svgr': {'tests': ['tsvgx', 09:13:28 INFO - 'tsvgr_opacity', 09:13:28 INFO - 'tart', 09:13:28 INFO - 'tscrollx', 09:13:28 INFO - 'cart']}, 09:13:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['tsvgx', 09:13:28 INFO - 'tsvgr_opacity', 09:13:28 INFO - 'tart', 09:13:28 INFO - 'tscrollx', 09:13:28 INFO - 'cart']}, 09:13:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'tests': ['tp5o']}, 09:13:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'talos_options': ['--e10s'], 09:13:28 INFO - 'tests': ['tp5o']}, 09:13:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'talos_options': ['--xperf_path', 09:13:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:28 INFO - 'tests': ['tp5n']}, 09:13:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:28 INFO - 'talos_options': ['--e10s', 09:13:28 INFO - '--xperf_path', 09:13:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:28 INFO - 'tests': ['tp5n']}}, 09:13:28 INFO - 'talos.zip': {'path': '', 09:13:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:28 INFO - Running post-action listener: _resource_record_post_action 09:13:28 INFO - ##### 09:13:28 INFO - ##### Running create-virtualenv step. 09:13:28 INFO - ##### 09:13:28 INFO - Running pre-action listener: _resource_record_pre_action 09:13:28 INFO - Running main action method: create_virtualenv 09:13:28 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:13:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:13:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:13:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:28 INFO - Using real prefix '/usr' 09:13:28 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:13:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:30 INFO - Installing pip.................done. 09:13:30 INFO - Return code: 0 09:13:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:13: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')]} 09:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:30 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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:13:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:13:30 INFO - Using env: {'DISPLAY': ':0', 09:13:30 INFO - 'HOME': '/home/cltbld', 09:13:30 INFO - 'LANG': 'en_US.UTF-8', 09:13:30 INFO - 'LANGUAGE': 'en_US:en', 09:13:30 INFO - 'LOGNAME': 'cltbld', 09:13:30 INFO - 'MAIL': '/var/mail/cltbld', 09:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:30 INFO - 'MOZ_NO_REMOTE': '1', 09:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:30 INFO - 'NO_EM_RESTART': '1', 09:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:30 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:30 INFO - 'SHELL': '/bin/bash', 09:13:30 INFO - 'SHLVL': '1', 09:13:30 INFO - 'TERM': 'linux', 09:13:30 INFO - 'TMOUT': '86400', 09:13:30 INFO - 'USER': 'cltbld', 09:13:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:30 INFO - '_': '/tools/buildbot/bin/python'} 09:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:13:30 INFO - Cleaning up... 09:13:31 INFO - Return code: 0 09:13:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:13:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:13:31 INFO - Using env: {'DISPLAY': ':0', 09:13:31 INFO - 'HOME': '/home/cltbld', 09:13:31 INFO - 'LANG': 'en_US.UTF-8', 09:13:31 INFO - 'LANGUAGE': 'en_US:en', 09:13:31 INFO - 'LOGNAME': 'cltbld', 09:13:31 INFO - 'MAIL': '/var/mail/cltbld', 09:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:31 INFO - 'MOZ_NO_REMOTE': '1', 09:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:31 INFO - 'NO_EM_RESTART': '1', 09:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:31 INFO - 'SHELL': '/bin/bash', 09:13:31 INFO - 'SHLVL': '1', 09:13:31 INFO - 'TERM': 'linux', 09:13:31 INFO - 'TMOUT': '86400', 09:13:31 INFO - 'USER': 'cltbld', 09:13:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:31 INFO - '_': '/tools/buildbot/bin/python'} 09:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:31 INFO - Downloading/unpacking psutil>=0.7.1 09:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:33 INFO - Installing collected packages: psutil 09:13:33 INFO - Running setup.py install for psutil 09:13:33 INFO - building 'psutil._psutil_linux' extension 09:13:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:13:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:13:33 INFO - building 'psutil._psutil_posix' extension 09:13:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:13:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:13:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:33 INFO - Successfully installed psutil 09:13:33 INFO - Cleaning up... 09:13:33 INFO - Return code: 0 09:13:33 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:13:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:13:33 INFO - Using env: {'DISPLAY': ':0', 09:13:33 INFO - 'HOME': '/home/cltbld', 09:13:33 INFO - 'LANG': 'en_US.UTF-8', 09:13:33 INFO - 'LANGUAGE': 'en_US:en', 09:13:33 INFO - 'LOGNAME': 'cltbld', 09:13:33 INFO - 'MAIL': '/var/mail/cltbld', 09:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:33 INFO - 'MOZ_NO_REMOTE': '1', 09:13:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:33 INFO - 'NO_EM_RESTART': '1', 09:13:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:33 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:33 INFO - 'SHELL': '/bin/bash', 09:13:33 INFO - 'SHLVL': '1', 09:13:33 INFO - 'TERM': 'linux', 09:13:33 INFO - 'TMOUT': '86400', 09:13:33 INFO - 'USER': 'cltbld', 09:13:33 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:33 INFO - '_': '/tools/buildbot/bin/python'} 09:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:33 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:13:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:13:35 INFO - Installing collected packages: mozsystemmonitor 09:13:35 INFO - Running setup.py install for mozsystemmonitor 09:13:35 INFO - Successfully installed mozsystemmonitor 09:13:35 INFO - Cleaning up... 09:13:35 INFO - Return code: 0 09:13:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:35 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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:13:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:13:35 INFO - Using env: {'DISPLAY': ':0', 09:13:35 INFO - 'HOME': '/home/cltbld', 09:13:35 INFO - 'LANG': 'en_US.UTF-8', 09:13:35 INFO - 'LANGUAGE': 'en_US:en', 09:13:35 INFO - 'LOGNAME': 'cltbld', 09:13:35 INFO - 'MAIL': '/var/mail/cltbld', 09:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:35 INFO - 'MOZ_NO_REMOTE': '1', 09:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:35 INFO - 'NO_EM_RESTART': '1', 09:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:35 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:35 INFO - 'SHELL': '/bin/bash', 09:13:35 INFO - 'SHLVL': '1', 09:13:35 INFO - 'TERM': 'linux', 09:13:35 INFO - 'TMOUT': '86400', 09:13:35 INFO - 'USER': 'cltbld', 09:13:35 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:35 INFO - '_': '/tools/buildbot/bin/python'} 09:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:13:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:38 INFO - Downloading docopt-0.6.1.tar.gz 09:13:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:38 INFO - Running setup.py install for blobuploader 09:13:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:38 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:13:38 INFO - Running setup.py install for requests 09:13:39 INFO - Running setup.py install for docopt 09:13:39 INFO - Successfully installed blobuploader requests docopt 09:13:39 INFO - Cleaning up... 09:13:39 INFO - Return code: 0 09:13:39 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:39 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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:13:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:39 INFO - Using env: {'DISPLAY': ':0', 09:13:39 INFO - 'HOME': '/home/cltbld', 09:13:39 INFO - 'LANG': 'en_US.UTF-8', 09:13:39 INFO - 'LANGUAGE': 'en_US:en', 09:13:39 INFO - 'LOGNAME': 'cltbld', 09:13:39 INFO - 'MAIL': '/var/mail/cltbld', 09:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:39 INFO - 'MOZ_NO_REMOTE': '1', 09:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:39 INFO - 'NO_EM_RESTART': '1', 09:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:39 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:39 INFO - 'SHELL': '/bin/bash', 09:13:39 INFO - 'SHLVL': '1', 09:13:39 INFO - 'TERM': 'linux', 09:13:39 INFO - 'TMOUT': '86400', 09:13:39 INFO - 'USER': 'cltbld', 09:13:39 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:39 INFO - '_': '/tools/buildbot/bin/python'} 09:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:13:39 INFO - Running setup.py (path:/tmp/pip-29Ge93-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:13:39 INFO - Running setup.py (path:/tmp/pip-NXpNuc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:13:39 INFO - Running setup.py (path:/tmp/pip-be9IZt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:13:39 INFO - Running setup.py (path:/tmp/pip-wa9yhL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:13:39 INFO - Running setup.py (path:/tmp/pip-fKfHAY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:13:39 INFO - Running setup.py (path:/tmp/pip-CSRkA1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:13:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:13:39 INFO - Running setup.py (path:/tmp/pip-IYKj4g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:13:40 INFO - Running setup.py (path:/tmp/pip-8OvlfY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:13:40 INFO - Running setup.py (path:/tmp/pip-ijliX0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:13:40 INFO - Running setup.py (path:/tmp/pip-V5u6UE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:13:40 INFO - Running setup.py (path:/tmp/pip-AQzoix-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:13:40 INFO - Running setup.py (path:/tmp/pip-MeQyAt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:13:40 INFO - Running setup.py (path:/tmp/pip-32ktSI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:13:40 INFO - Running setup.py (path:/tmp/pip-fE21EZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:13:40 INFO - Running setup.py (path:/tmp/pip-YQ7ty3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:13:40 INFO - Running setup.py (path:/tmp/pip-kbviWf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:13:40 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:13:40 INFO - Running setup.py (path:/tmp/pip-WAOL7p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:13:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:40 INFO - Running setup.py install for manifestparser 09:13:40 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:13:40 INFO - Running setup.py install for mozcrash 09:13:40 INFO - Running setup.py install for mozdebug 09:13:41 INFO - Running setup.py install for mozdevice 09:13:41 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for mozfile 09:13:41 INFO - Running setup.py install for mozhttpd 09:13:41 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for mozinfo 09:13:41 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for mozInstall 09:13:41 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for mozleak 09:13:41 INFO - Running setup.py install for mozlog 09:13:41 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for moznetwork 09:13:41 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:13:41 INFO - Running setup.py install for mozprocess 09:13:41 INFO - Running setup.py install for mozprofile 09:13:42 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:13:42 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:13:42 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:13:42 INFO - Running setup.py install for mozrunner 09:13:42 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:13:42 INFO - Running setup.py install for mozscreenshot 09:13:42 INFO - Running setup.py install for moztest 09:13:42 INFO - Running setup.py install for mozversion 09:13:42 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:13:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:42 INFO - Cleaning up... 09:13:42 INFO - Return code: 0 09:13:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:42 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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:13:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:42 INFO - Using env: {'DISPLAY': ':0', 09:13:42 INFO - 'HOME': '/home/cltbld', 09:13:42 INFO - 'LANG': 'en_US.UTF-8', 09:13:42 INFO - 'LANGUAGE': 'en_US:en', 09:13:42 INFO - 'LOGNAME': 'cltbld', 09:13:42 INFO - 'MAIL': '/var/mail/cltbld', 09:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:42 INFO - 'MOZ_NO_REMOTE': '1', 09:13:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:42 INFO - 'NO_EM_RESTART': '1', 09:13:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:42 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:42 INFO - 'SHELL': '/bin/bash', 09:13:42 INFO - 'SHLVL': '1', 09:13:42 INFO - 'TERM': 'linux', 09:13:42 INFO - 'TMOUT': '86400', 09:13:42 INFO - 'USER': 'cltbld', 09:13:42 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:42 INFO - '_': '/tools/buildbot/bin/python'} 09:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:13:42 INFO - Running setup.py (path:/tmp/pip-zdE_L9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:13:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:13:42 INFO - Running setup.py (path:/tmp/pip-9JPwgc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:13:42 INFO - Running setup.py (path:/tmp/pip-hvjQxj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:13:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:13:42 INFO - Running setup.py (path:/tmp/pip-tyPruc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:13:43 INFO - Running setup.py (path:/tmp/pip-3jkIc7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:13:43 INFO - Running setup.py (path:/tmp/pip-sYTVnS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:13:43 INFO - Running setup.py (path:/tmp/pip-nhx3HP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:13:43 INFO - Running setup.py (path:/tmp/pip-XeCsWa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:13:43 INFO - Running setup.py (path:/tmp/pip-DGAAuR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:13:43 INFO - Running setup.py (path:/tmp/pip-JhuqbS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:13:43 INFO - Running setup.py (path:/tmp/pip-lwSABs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:13:43 INFO - Running setup.py (path:/tmp/pip-SvwUeT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:13:43 INFO - Running setup.py (path:/tmp/pip-cp_0jb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:13:43 INFO - Running setup.py (path:/tmp/pip-wPL7DX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:13:43 INFO - Running setup.py (path:/tmp/pip-zUSuRY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:13:43 INFO - Running setup.py (path:/tmp/pip-GSovJY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:13:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:13:43 INFO - Running setup.py (path:/tmp/pip-RF7luz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:45 INFO - Downloading blessings-1.6.tar.gz 09:13:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:45 INFO - Installing collected packages: blessings 09:13:45 INFO - Running setup.py install for blessings 09:13:45 INFO - Successfully installed blessings 09:13:45 INFO - Cleaning up... 09:13:46 INFO - Return code: 0 09:13:46 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:13:46 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:13:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:13:46 INFO - Reading from file tmpfile_stdout 09:13:46 INFO - Current package versions: 09:13:46 INFO - argparse == 1.2.1 09:13:46 INFO - blessings == 1.6 09:13:46 INFO - blobuploader == 1.2.4 09:13:46 INFO - docopt == 0.6.1 09:13:46 INFO - manifestparser == 1.1 09:13:46 INFO - mozInstall == 1.12 09:13:46 INFO - mozcrash == 0.16 09:13:46 INFO - mozdebug == 0.1 09:13:46 INFO - mozdevice == 0.47 09:13:46 INFO - mozfile == 1.2 09:13:46 INFO - mozhttpd == 0.7 09:13:46 INFO - mozinfo == 0.9 09:13:46 INFO - mozleak == 0.1 09:13:46 INFO - mozlog == 3.1 09:13:46 INFO - moznetwork == 0.27 09:13:46 INFO - mozprocess == 0.22 09:13:46 INFO - mozprofile == 0.27 09:13:46 INFO - mozrunner == 6.11 09:13:46 INFO - mozscreenshot == 0.1 09:13:46 INFO - mozsystemmonitor == 0.0 09:13:46 INFO - moztest == 0.7 09:13:46 INFO - mozversion == 1.4 09:13:46 INFO - psutil == 3.1.1 09:13:46 INFO - requests == 1.2.3 09:13:46 INFO - wsgiref == 0.1.2 09:13:46 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:46 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 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:13:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:46 INFO - Using env: {'DISPLAY': ':0', 09:13:46 INFO - 'HOME': '/home/cltbld', 09:13:46 INFO - 'LANG': 'en_US.UTF-8', 09:13:46 INFO - 'LANGUAGE': 'en_US:en', 09:13:46 INFO - 'LOGNAME': 'cltbld', 09:13:46 INFO - 'MAIL': '/var/mail/cltbld', 09:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:46 INFO - 'MOZ_NO_REMOTE': '1', 09:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:46 INFO - 'NO_EM_RESTART': '1', 09:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:46 INFO - 'SHELL': '/bin/bash', 09:13:46 INFO - 'SHLVL': '1', 09:13:46 INFO - 'TERM': 'linux', 09:13:46 INFO - 'TMOUT': '86400', 09:13:46 INFO - 'USER': 'cltbld', 09:13:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:46 INFO - '_': '/tools/buildbot/bin/python'} 09:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:13:46 INFO - Cleaning up... 09:13:46 INFO - Return code: 0 09:13:46 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:13:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:13:46 INFO - Using env: {'DISPLAY': ':0', 09:13:46 INFO - 'HOME': '/home/cltbld', 09:13:46 INFO - 'LANG': 'en_US.UTF-8', 09:13:46 INFO - 'LANGUAGE': 'en_US:en', 09:13:46 INFO - 'LOGNAME': 'cltbld', 09:13:46 INFO - 'MAIL': '/var/mail/cltbld', 09:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:46 INFO - 'MOZ_NO_REMOTE': '1', 09:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:46 INFO - 'NO_EM_RESTART': '1', 09:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:46 INFO - 'SHELL': '/bin/bash', 09:13:46 INFO - 'SHLVL': '1', 09:13:46 INFO - 'TERM': 'linux', 09:13:46 INFO - 'TMOUT': '86400', 09:13:46 INFO - 'USER': 'cltbld', 09:13:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:46 INFO - '_': '/tools/buildbot/bin/python'} 09:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:46 INFO - Downloading/unpacking jsonschema 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:48 INFO - Downloading/unpacking functools32 (from jsonschema) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:49 INFO - warning: no files found matching '*.txt' 09:13:49 INFO - no previously-included directories found matching 'build' 09:13:49 INFO - no previously-included directories found matching 'dist' 09:13:49 INFO - no previously-included directories found matching '.git*' 09:13:49 INFO - Installing collected packages: jsonschema, functools32 09:13:50 INFO - Running setup.py install for functools32 09:13:50 INFO - warning: no files found matching '*.txt' 09:13:50 INFO - no previously-included directories found matching 'build' 09:13:50 INFO - no previously-included directories found matching 'dist' 09:13:50 INFO - no previously-included directories found matching '.git*' 09:13:50 INFO - Successfully installed jsonschema functools32 09:13:50 INFO - Cleaning up... 09:13:50 INFO - Return code: 0 09:13:50 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:13: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')]} 09:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f529b733f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111c600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:13: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', 'flake8'] in /builds/slave/test-pgo/build 09:13: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 flake8 09:13:50 INFO - Using env: {'DISPLAY': ':0', 09:13:50 INFO - 'HOME': '/home/cltbld', 09:13:50 INFO - 'LANG': 'en_US.UTF-8', 09:13:50 INFO - 'LANGUAGE': 'en_US:en', 09:13:50 INFO - 'LOGNAME': 'cltbld', 09:13:50 INFO - 'MAIL': '/var/mail/cltbld', 09:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:50 INFO - 'MOZ_NO_REMOTE': '1', 09:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:50 INFO - 'NO_EM_RESTART': '1', 09:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:13:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:13:50 INFO - 'SHELL': '/bin/bash', 09:13:50 INFO - 'SHLVL': '1', 09:13:50 INFO - 'TERM': 'linux', 09:13:50 INFO - 'TMOUT': '86400', 09:13:50 INFO - 'USER': 'cltbld', 09:13:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:50 INFO - '_': '/tools/buildbot/bin/python'} 09:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:50 INFO - Downloading/unpacking flake8 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:13:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:13:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:13:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:53 INFO - no previously-included directories found matching 'docs/_build' 09:13:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:13:53 INFO - Running setup.py install for pep8 09:13:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:53 INFO - no previously-included directories found matching 'docs/_build' 09:13:53 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:13:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:13:53 INFO - Cleaning up... 09:13:53 INFO - Return code: 0 09:13:53 INFO - Running post-action listener: _resource_record_post_action 09:13:53 INFO - Running post-action listener: _start_resource_monitoring 09:13:53 INFO - Starting resource monitoring. 09:13:53 INFO - ##### 09:13:53 INFO - ##### Running install step. 09:13:53 INFO - ##### 09:13:53 INFO - Running pre-action listener: _resource_record_pre_action 09:13:53 INFO - Running main action method: install 09:13:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:13:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:13:53 INFO - Reading from file tmpfile_stdout 09:13:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:13:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:13:54 INFO - Reading from file tmpfile_stdout 09:13:54 INFO - Output received: 09:13:54 INFO - Usage: mozinstall [options] installer 09:13:54 INFO - Options: 09:13:54 INFO - -h, --help show this help message and exit 09:13:54 INFO - -d DEST, --destination=DEST 09:13:54 INFO - Directory to install application into. [default: 09:13:54 INFO - "/builds/slave/test-pgo"] 09:13:54 INFO - --app=APP Application being installed. [default: firefox] 09:13:54 INFO - mkdir: /builds/slave/test-pgo/build/application 09:13:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:13:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:14:08 INFO - Reading from file tmpfile_stdout 09:14:08 INFO - Output received: 09:14:08 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:14:08 INFO - Running post-action listener: _resource_record_post_action 09:14:08 INFO - ##### 09:14:08 INFO - ##### Running run-tests step. 09:14:08 INFO - ##### 09:14:08 INFO - Running pre-action listener: _resource_record_pre_action 09:14:08 INFO - Running main action method: run_tests 09:14:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:14:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:14:08 INFO - Python 2.7.3 09:14:08 INFO - Return code: 0 09:14:08 INFO - grabbing minidump binary from tooltool 09:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:08 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 0x11ab350>, '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 0x11ac250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11ac6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:14:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:14:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:14:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:08 INFO - Return code: 0 09:14:08 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:14:08 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:14:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:14:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:14:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:14:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:14:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:14:09 INFO - Return code: 0 09:14:09 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/1456411770/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:14:09 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/1456411770/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost 09:14:09 INFO - Using env: {'DISPLAY': ':0', 09:14:09 INFO - 'HOME': '/home/cltbld', 09:14:09 INFO - 'LANG': 'en_US.UTF-8', 09:14:09 INFO - 'LANGUAGE': 'en_US:en', 09:14:09 INFO - 'LOGNAME': 'cltbld', 09:14:09 INFO - 'MAIL': '/var/mail/cltbld', 09:14:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:14:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:09 INFO - 'MOZ_NO_REMOTE': '1', 09:14:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:14:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:14:09 INFO - 'NO_EM_RESTART': '1', 09:14:09 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:14:09 INFO - 'PWD': '/builds/slave/test-pgo', 09:14:09 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:14:09 INFO - 'SHELL': '/bin/bash', 09:14:09 INFO - 'SHLVL': '1', 09:14:09 INFO - 'TERM': 'linux', 09:14:09 INFO - 'TMOUT': '86400', 09:14:09 INFO - 'USER': 'cltbld', 09:14:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193', 09:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:09 INFO - '_': '/tools/buildbot/bin/python'} 09:14:09 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/1456411770/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] with output_timeout 3600 09:14:09 INFO - mozversion INFO | application_buildid: 20160225064930 09:14:09 INFO - mozversion INFO | application_changeset: b3bc3d627637cc2258e9f24f1a3775c4437038af 09:14:09 INFO - mozversion INFO | application_display_name: Nightly 09:14:09 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:09 INFO - mozversion INFO | application_name: Firefox 09:14:09 INFO - mozversion INFO | application_remotingname: firefox 09:14:09 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:14:09 INFO - mozversion INFO | application_vendor: Mozilla 09:14:09 INFO - mozversion INFO | application_version: 45.0 09:14:09 INFO - mozversion INFO | platform_buildid: 20160225064930 09:14:09 INFO - mozversion INFO | platform_changeset: b3bc3d627637cc2258e9f24f1a3775c4437038af 09:14:09 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:14:09 INFO - mozversion INFO | platform_version: 45.0 09:14:09 INFO - 2016-02-25 09:14:09,903 DEBUG : using testdate: 1456420449 09:14:09 INFO - 2016-02-25 09:14:09,903 DEBUG : actual date: 1456420449 09:14:09 INFO - 2016-02-25 09:14:09,912 INFO : starting webserver on 'localhost:41106' 09:14:09 INFO - 2016-02-25 09:14:09,913 INFO : Starting test suite talos-linux64-ix-005 09:14:09 INFO - 2016-02-25 09:14:09,913 INFO : Starting test dromaeo_css 09:14:09 INFO - 2016-02-25 09:14:09,913 DEBUG : operating with platform_type : linux_ 09:14:09 INFO - 2016-02-25 09:14:09,914 INFO : Initialising browser for dromaeo_css test... 09:14:09 INFO - 2016-02-25 09:14:09,928 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsYmM8E/profile http://localhost:41106/getInfo.html 09:14:13 INFO - 2016-02-25 09:14:13,726 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:14:13 INFO - 2016-02-25 09:14:13,726 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:14:13 INFO - 2016-02-25 09:14:13,726 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:14:13 INFO - 2016-02-25 09:14:13,726 DEBUG : BROWSER_OUTPUT: __metrics 09:14:13 INFO - 2016-02-25 09:14:13,869 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:14:14 INFO - 2016-02-25 09:14:14,631 INFO : Browser initialized. 09:14:14 INFO - 2016-02-25 09:14:14,632 INFO : Running cycle 1/1 for dromaeo_css test... 09:14:14 INFO - 2016-02-25 09:14:14,632 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsYmM8E/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:14:26 INFO - 2016-02-25 09:14:26,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 133558272 09:14:26 INFO - 2016-02-25 09:14:26,319 DEBUG : BROWSER_OUTPUT: 09:15:17 INFO - 2016-02-25 09:15:17,533 DEBUG : BROWSER_OUTPUT: 1456420517531 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:17:26 INFO - 2016-02-25 09:17:26,143 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41106/tests/dromaeo/cssquery-ext.html) 09:17:27 INFO - 2016-02-25 09:17:27,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 222658560 09:17:27 INFO - 2016-02-25 09:17:27,112 DEBUG : BROWSER_OUTPUT: 09:20:29 INFO - 2016-02-25 09:20:29,112 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-ext.html (next: http://localhost:41106/tests/dromaeo/cssquery-jquery.html) 09:20:29 INFO - 2016-02-25 09:20:29,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 213749760 09:20:29 INFO - 2016-02-25 09:20:29,679 DEBUG : BROWSER_OUTPUT: 09:23:31 INFO - 2016-02-25 09:23:31,470 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41106/tests/dromaeo/cssquery-mootools.html) 09:23:32 INFO - 2016-02-25 09:23:32,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 206069760 09:23:32 INFO - 2016-02-25 09:23:32,037 DEBUG : BROWSER_OUTPUT: 09:26:33 INFO - 2016-02-25 09:26:33,735 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41106/tests/dromaeo/cssquery-prototype.html) 09:26:34 INFO - 2016-02-25 09:26:34,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 196694016 09:26:34 INFO - 2016-02-25 09:26:34,300 DEBUG : BROWSER_OUTPUT: 09:29:36 INFO - 2016-02-25 09:29:36,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41106/tests/dromaeo/cssquery-yui.html) 09:29:37 INFO - 2016-02-25 09:29:37,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 198205440 09:29:37 INFO - 2016-02-25 09:29:37,302 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,330 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/dromaeo/cssquery-yui.html (next: http://localhost:41106/tests/dromaeo/cssquery-dojo.html) 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: RSS: Main: 241750016 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3287.819169945653;3377.729781617471;3401.3197360527884;3406.795592264464;3425.828708564571;5534.667919924807;5638.774449022039;5663.490285874271;5664.660120392779;5695.316562012554;5655.632383895044;5678.375378708339;5692.402297758737;5692.601517893739;5701.258836351269;4856.660033797635;5104.770285337168;5145.614078944075;5203.245529398235;5218.112920803473;4879.682820616648;5022.372203474566;5046.09170349337;5046.747662616873;5053.242013697938;5015.197568389059;5017.448080711119;5025.422076461217;5088.491150884919;5109.131447653889;5031.647784655075;5050.242463630448;5052.494750524955;5078.4159821620615;5081.085404627165;4923.606111511071;4950.405951285833;4973.975130124362;4979.053979743837;5049.469805670388;5318.707471089088;5392.083345831218;5400.675959442446;5405.324334458192;5429.4027656957705;5973.820785376446;6004.519638428916;6052.909206361908;6058.818235452944;6068.393160683941;5792.478676919055;5872.970635146839;5998.520118390518;6071.089336599502;6100.23747031621;5568.331800184003;5578.972105139448;5581.2186293918885;5588.385277619459;5589.469000444951;6032.728527216286;6043.607165534227;6045.274567051894;6045.72794224261;6047.516198704093;5620.91568626471;5686.2607860977805;5792.9710351447975;5867.970660146672;5938.524918006549;5961.493273071751;6036.939630603725;6038.698065096728;6042.788502402394;6047.213862197887;5974.193483879708;6001.699915004232;6006.639601623917;6016.42843929826;6019.097135429721;5838.095095260242;5877.764889404376;5962.165296858443;5994.100884867305;5999.850003749854;4578.267477203632;4613.930791038137;4633.142868569258;4640.6751527393435;4667.113248482778;3948.1511475033008;4142.378643203544;4156.189543039139;4166.375043743463;4174.728642638219;5508.862278442991;5555.250041244381;5572.192032155314;5594.692291923902;5630.5495560355075;5981.6710080945095;5984.401559844068;5985.940140598538;5989.161517387569;5996.790112346047;5662.235598194274;5669.262995810518;5670.291213598301;5670.744816483269;5672.234248376499;5005.449400565934;5042.495750424928;5115.8721031973755;5129.897402051938;5142.997115562504;6234.781782637677;6240.126382306481;6241.126242326078;6242.625442473466;6247.344028876993;6091.147239386578;6092.725827337692;6092.878142437038;6095.878082438415;6096.664683442275;6001.159837622824;6069.2110025696375;6143.139960405458;6171.753129874754;6182.381761823782;5960.105984102246;6001.579889407874;6003.609765365237;6008.639481631116;6009.278886533644;5699.173619825185;6057.697115144313;6098.969505152446;6113.083037544404;6126.172966649446;5719.828405147852;5774.82675519735;5782.884342313046;5786.884262314646;5799.623024503394;5885.087811389241;5922.052471604523;5938.287405511366;5939.07944268639;5942.6731529766275;5551.500364967173;5658.773649053992;5819.0689489681445;5820.592558520862;5822.301323841166;5879.5002424794375;5947.613405128653;5966.284045914518;5975.671338076274;5979.043353063489;5808.76764929415;5846.56150788684;5848.970755146197;5851.327097383685;5852 09:32:39 INFO - 2016-02-25 09:32:39,589 DEBUG : BROWSER_OUTPUT: |1;ext.html;604.4378727783163;638.3839594791027;640.3980258556957;644.1400730025416;702.2696395748419;30900.690993090062;31561.526577101333;31602.36795264096;38279.617203827955;38397.232055358945;42724.57275427245;43200;43408.13183736323;43450.56549434505;43526;8931.285497160212;9121.589528471202;9125.498097604663;9126.087391260853;9127.589258483384;8192.713255036075;8396.496210227375;10495.842562361571;10752.569897204103;10786.838197427045;10528.473576321192;10656.627018054369;10664.360138391683;10665.200109991743;10667.466626668675;10515.631952881613;10618.893811061867;10621.415822129818;10627.096696780784;10647.30792498485;10379;10549.789004219872;10569.78860422795;10575.576976920915;10580.841287380696;39762.403563946566;40131.59868401337;40200.39699404511;40320.39519407211;40326.798366007984;3747.5502939647145;3769.3968964965748;3828.444875493066;4218.261804184286;4281.443412356373;30898.382032359226;31130.533042004387;31282.374352512823;31369.215769605715;31768.682313176803;4198.685098617585;4212.873613791591;4217.514110320212;4314.913701725979;4349.391085248068;269517;270649;272278;272547;413807;48345.27482087771;61226;62814;62892;63039.36960630335;57306.140407893916;58076;58113.128303075486;58118;58143.709281453324;16033.11817850006;16254.51236462908;16292.674146517003;16298.10360430164;16298.42955496552;12295.754084918251;12458.190217635825;12470.563530276579;12476.875231247572;12542;19006.61986760257;19248.133833977503;19403;19414.417567473;19472.123754431086;7707.691692332244;7865.252800983897;7894.6842126314305;7936.246056624612;8019;938.432248489654;1049.9055085042382;1054.2093429927554;1086.684861390188;1109.5062697099875;25113.497730045663;25384.492310153695;25429.491410171693;25740.742592573835;28997.275068123043;1002.6891663584312;1027.9794404111876;1063.0963680871353;1091.7161538000178;1139.6865861888048;13192.47230110804;13855.37650805696;15530.456434024754;15630;16194.271257793192;339478;344107;344155;344259;344484;53449.198262026104;53869;53977;54098;54238.457615423344;1238.4427007846505;1288.9484420623257;1364.3178410794603;1437.2741765408612;1449.4202319072244;27427.31431714223;28199.859000704866;28283.57574636341;29143.271418213866;29666.110016699535;42884.1423171541;43291.56708432875;43301;43379.78310108429;43385.78307108444;202988;203584;203646;204024;204429;30370.392592147593;31127.84436077805;31140.532892006184;31322.373552528366;31333;41363;43397;43877.56122438863;44041.339379909965;44231.33652995143;35355.82322088476;35565.82217088898;35964;36256.63743362533;36576.63423365732;35610.64389356073;35630;36076.639233607326;36400.81799590985;36460.27079458449;34532.82733586316;35149.29701405906;35328.64671353254;35355.116122097155;35375.46936796002;31779.682203177672;32506;33222.33555328928;33327;33449.66550334466;24948.875255623607;25424.237272881845;26439.603405948543;27777.166684999484;28087.859560702884 09:32:39 INFO - 2016-02-25 09:32:39,590 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.51505375807125;389.66683485619797;392.0707922224327;393.62802151966395;395.20958083832335;14734.926325368386;15252.694946101072;16685.16574171291;18457;18477.445676629704;17224.224909879053;17330.82669173308;17348.479545613653;17491.125443727826;17898.463046108638;6173.783917562898;6338.556301058928;6350.079238510413;6350.936490635081;6353.332900045498;4603.079384123172;4714.080754252922;6232.345603711613;6881.208661003973;7040.47196460265;5312.2828418163635;6921.307869213064;6999.545029573087;7013.228544860087;7027.964860175692;5446.183072539131;5447.754851031713;5450.509454149106;5458.590605704588;5485.835424937239;5265.262863199156;5287.841364759025;5417.26866872971;5421.728913554307;5428.5928555358505;12882.613521594367;12900.22598644075;13162.736745265041;13425.194488330633;13478.932605337008;2235.6422972324513;2310.8844557772045;2381.452265978817;2422.7456117107627;2441.2554170977846;9810.117089461912;10096.545655445523;10757.569697212102;10971.451427428676;14489.927550362287;1760.6390689908537;1872.2136702584946;1911.5603411215359;1929.2283086765267;1973.9506512337164;61451;62863.05705414422;63235;63580.36419635791;64824.675876620786;10086.344387614929;10296.897031029745;10318.896811031944;10338.689839304832;10366.740831479274;12713.809292860615;12726.745465090831;12742.80885786714;12765.234085954688;12838.48646054166;7776.144624091346;7783.260590243918;7798.298153166243;7824.92175078242;7840.960795195988;7353.742619008309;7514.098308202942;7566.508176968479;7821.452498325063;7900.644470998819;3925.7448265862627;4192.517860446088;4212.936805947913;4240.300350442142;4247.4265974093105;1691.196681576266;1692.1877498800645;1757.314647287559;1770.2034084661695;1774.325756212631;920.7513971227731;926.5321012888448;991.2218908157062;1011.8735158105237;1063.1016790811752;15827.287772050286;16882.831171688125;17044.233009514603;17204.1397930103;17749.556261093578;958.2717134977468;979.3627175324831;982.1946004276567;995.7510622344414;1010.0808264479289;9842.704718858446;9998.750031249132;10377.377357358582;10528.473576321154;10605.681829545127;614547;622745;630322;630512;639996;82089;82606.58696706599;85125;86050.13949860547;87690;1143.7369405036889;1162.2503485252134;1162.610525473959;1174.0900801878443;1178.4166837415535;15045.172515511314;15689.372425102869;16084.43704470338;16318.347266109222;16472.09403482772;36866.44700329444;37084.44373334348;37877.81061094677;38169;38986;45273;45600.54399455963;47311.05377892354;47318.29022564596;50492.24261636152;9560.378575392577;9589.664361747306;9799.216062714822;10089.545970431494;10095.040971107734;11372.578427696539;11427;13011.154274972096;15374.538763837103;17538;14481.710365792414;14583.19792411385;14711;14717.632059198606;15213.77179342333;13943.30283485842;14525.27373631293;14533.273336333352;14801.333939972512;14836.777448338067;14113.788293175816;14786.186759728318;14981.250937453302;15103.54689359321;15328.156951367782;9587.856182157133;9628;9681.564329605044;9806.509674516388;9835.164011058854;10295.691129266133;10338.12125969301;10730.463476825971;15295.617609559406;15542.222888855738 09:32:39 INFO - 2016-02-25 09:32:39,590 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1502.6168327076593;1580.4231455518739;1585.4609432792847;1648.563130770346;1709.4529750479844;7745.496542724726;7911.406644501657;7916.406269529787;7932.127465978738;7948.880766788503;6990.650467476631;7203.6038017908995;7209.495335326529;7563.6218189090605;7977.361811055116;2258.9096436142577;2281.4752606900424;2311.2603966730653;2323.94190145246;2324.1168356024687;2271.136967952176;2309.6304591265402;2312.8843557822124;2313.2366319114735;2313.2713195343413;1971.5859669469428;2002.9499262518407;2217.6895234667077;2288.6795848581214;2290.0954123121355;2269.9773002270094;2274;2307.3193407944614;2307.8846057697133;2309.549637820609;2299.9655005174936;2328.52265285616;2335.871527066011;2340.6254999200046;2356.4462351347593;7816.765497035098;7934.285914267686;7942.324902383304;7949.244821741925;7955.244251796128;4732.83435079774;4844.224924012176;4848.321235027099;4850.878728031792;4855.223164293696;3002.2944608017096;3017;3033.3326668132977;3040.8175509469283;3058.4494790937624;1051.568856768729;1072.1583556907867;1103.7130346109989;1110.1784679337313;1110.21729680575;10655.733606659818;10795;10801.189910756724;10816.296940698829;10885.891141088488;4074;4125.752454852658;4164.75011499311;4186.246475634415;4218.641415479658;8923.910760892439;8925.107489251022;8931.866022009675;8935.28517718581;8935.463872167691;4701.576858082789;4788.497207793202;4789.976050119797;4790.592799612003;4790.880227994328;4425.137098265807;4427.358033085185;4432.778361081933;4434.82260709568;4434.84478043267;3425.845836937344;3452.067941655739;3475.8957231283102;3495.7727747696317;3511.3328467591077;984.3650845204803;1044.5247412427327;1048.433845723301;1050.401271275381;1065.2969040433277;1030.0935177044153;1109.7614012987246;1132.416805345248;1245.8006718924928;1344.610063081715;7499.2500749924575;7568.8486230276185;7671.693132274758;7673.61631918413;7697.499662521918;629.7764293675771;640.2509064394669;642.0754114075716;643.1510406263687;779.8479296537121;5849.064149736022;5870.090136028923;5969.492593129546;5980.73086711099;5982.551308651869;9249.306302027375;9430.198433133124;9456.432614043179;9464.621415143318;9465.479398633004;9279.39683920543;9299.581518831668;9451.574679139318;9458.479783611967;9466.668666596774;7303.634818259172;7363.926360736431;7439.814004649818;7453.105627324648;7458.366038886756;7507.887381689388;7567.167611562613;7593.772186834404;7597.848043039219;7600.429967752551;8284.171582841667;8639.308855291687;8674.956625216833;8691;8697.478151310941;8652.134786521297;8768.079351667982;8903.109689031304;8908.955455222682;8913.37606367574;7064.858702826017;7091.078159839188;7149.9285007149265;7155.463340249398;7207.459440542085;7519.43604229674;7547.28300811422;7548.509346892434;7559;7571.242875712384;6426.807195784134;7248.130224373109;7278;7283.7450689225625;7427.665755040928;6935.687894044881;6968.825779355557;6991.580505169706;7021.473389495911;7195.712171513081;7162.641867906688;7203.135623725187;7207.459440541876;7212.4590655699985;7218.855622887618;6770.492213084137;6777.762778302736;6797.5581587196675;6804.591724496546;6806.897896531457;6793.626350550767;6889.758858439931;6935.895961560682;6941.861162776817;6955.304469553206 09:32:39 INFO - 2016-02-25 09:32:39,591 DEBUG : BROWSER_OUTPUT: |4;prototype.html;345.3886620681394;354.41875324467867;357.3745944596955;359.57749644168115;362.2917196880098;6111.6027458215185;6157.476614487774;6161.476274516672;6166.691665416723;6179.567430279876;5842.941570584293;5931.436513531207;6000.609960352574;6080.787172448965;6106.389361063892;4692.061587682477;4693.765311734417;4698.177818881699;4720.221163508017;4723.740194289313;4442.444694413199;4682.555157260055;4692.085043416535;4709.670323077386;4720.504347043562;4542.273236282195;4555.931661025087;4634.930476042845;4669.042846216517;4681.438227412716;4654.371659825931;4656.045510670305;4662.860114196579;4668.089722504113;4679.532046795327;4117;4125.97937010316;4126.896827579305;4128.380742888561;4135.462389889295;5701.77192912283;5784.508316793078;5801.332846722638;5801.419858014207;5801.6228945118855;5399.83800485986;5423.159410291371;5428;5432.239486471857;5433.429489903582;6486.416222539947;6488.643124628144;6495.740170393178;6497.350264973512;6504.967475162642;4519.118771839492;4527.84152554659;4530.275155975029;4530.275155975029;4532.592066713979;6545.476361891037;6609.206895172562;6666.500012499082;6690.498212634072;6694.93305066948;4622.9768851156205;4629.305604159403;4630.097131059411;4634.374359461497;4636.86089417318;4684.882877928011;4686.297055441711;4686.835960741358;4688.718676879398;4690.859274221779;5391;5397.298351214317;5400.594955378362;5406.135018397116;5410.296661433988;6334.841628959313;6519.837004074936;6523.151990241238;6528.118703974903;6530.216374035052;1635.4684727463623;1649.2331066054342;1653.3717187468685;1655.0152659167775;1656.2546853915546;1108.2519299472824;1110.9777804443866;1113.2819331531116;1133.5012594458412;1134.9035331996872;6335.271443783931;6345.841353966188;6359;6366.076918846741;6368.554201205964;6549.279579246279;6746.123004009545;6754.493412993947;6762.323767623198;6762.830929226809;6281.685915704196;6321.304656487782;6384.106225128486;6420.486361091102;6431.421172094534;3620.8551657933913;3666.926661466756;3669.1377526281303;3669.486271921933;3672.1737609038287;6849.315068493111;6984.126984126984;6985.615791131537;6989.965050174716;6996.370326670624;6939.514234003571;6967.233604303523;6968.059311992816;6970.616616086062;6979.4416446684145;6970.721171153198;6996.440284777307;7012.614306213207;7017.438604911595;7019.578825270398;6642.43539299165;6799.456043516606;6799.93200067993;6816.659167041727;6817.5909445433435;6716;6718.0594716738715;6720.966395167993;6731.865362692816;6733.494987876027;7011.1586609607175;7021.963630547289;7022.719091236498;7024.578525288499;7035.261297563887;4512.842050528216;4646.930296045494;4658.114958157939;4680.953190468052;4681.6722829401615;6742.123523941857;6747.156605424322;6752.628605426748;6752.831179220559;6754.898676519954;6539.378759017885;6543.247526534509;6544.280129185881;6550.967245163934;6555.836104097436;6442.484601231984;6551.5259066709605;6551.6069035858;6559.704813283318;6565.967170164118;6458.644774537445;6460.095586617783;6460.45086167681;6463.870722585428;6465.903011454926;6554.14796076507;6584;6589.6375699336995;6598.60408375499;6601.537892347682;6237.594556353823;6318.462930650946;6370.1718776558755;6404.135441715495;6416.101745755691 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: |5;yui.html;784.8194915169514;795.9840803183932;849.8470275350435;854.4873076154307;887.9556022198889;13124;13536.323183840817;13539.255340956244;13542.729145417097;13609.795853062213;12780.808287875689;12868.29224392658;12868.356582170902;12889.80665290019;12915.418806153722;689.7378995981545;696.1333140240408;803.0804728585751;805.4442434720054;815.5473712089788;808.8544062068826;810.4367464612097;813.0771574263224;816.4488969945263;818.3494122172887;721.7185297733888;846.0946786937978;849.7578190215789;851.2679095977454;852.228732996639;723.5477826358526;844.0926004545139;848.088305072047;850.0776657326808;853.894207001934;627.0468887291337;627.6830200748639;629.5247088448239;631.9336469670665;632.032989526026;13295.86704132956;13452.596422107352;13493.865061349457;13572.321383930863;13597.388117534734;11715.88284117165;11977.461014254379;12015.339156346397;12053.819192712117;12206.755864882742;10158.949205254;10263.281570290083;10322.812876519221;10325.587894757808;10360;3650.9817450912574;3672.6878215351726;3697.759645623026;3711.536057992751;3714.53568303962;51022.234666480035;51485.74257128728;51544.74227628875;51582.74208628933;51694.741526292506;9513.476758778334;9813.754656133511;9850.852237216448;9876.506174691236;9930.205583553443;17661.55846103832;17671.29314827418;17676.558086047695;17677.20452579637;17683.911580442273;7371.299726525972;7412.0364352633815;7419.740309089156;7508.962455187799;7608.31525162738;4298.118885628439;4338.175746608134;4420.955790442054;4440.711353761995;4444.799984000728;3065.4788685923445;3097.4734295169874;3099.3491366812773;3101.6278046634097;3102.7672924530752;563.3605857351935;568.4713216708418;571.2973043156941;574.666693317883;576.8586696259359;526.12137729991;582.6737027264745;583.4836169989505;608.7930103764742;652.6377860287491;8371.41400101987;8446.934267348515;8552.486850788973;8616.827663446697;8773.298136149093;518.2795913679988;537.0655060195232;592.4874983139639;605.3492495567194;622.6077571130159;3676.632336766302;3728.738988270795;3824.8661296854475;3857.5370955485523;3887.7084218683713;60300.39699603036;60356.79286414247;60371.09443358353;60497;60559.39440605626;52907.735461322445;54088.18867716988;54275.72862135743;54816.45183548193;54967.17549236765;48177;48278.5172148274;49432.50567494279;49575.256371154464;50424.99150016979;7090.929090709027;7251;7346.5592064476305;7414.925850741423;7472.962635186789;37221.813890930374;37682.62317376791;38862.6113738859;38895.41656875206;38965.80517097397;43779.562204378824;44043.55956440394;45841.54158458373;47790.283145752146;48249.5175048245;9744.610215591298;9885.159761420073;9902;10091.89908100939;10337.0696637303;11259.887401125883;12448.502059917502;12628.936855315664;12797.936010319889;12944.805827912778;10926.726831829268;11248.437578121226;11501.079913606723;11518.82721759191;11525.135614829089;10657.733556661145;11056.391898445663;11359;11420;11514.53941842317;9310.162085412345;9541.904580954102;9744.902550974399;10709.732256693645;10794.298370605886;9666.516674166403;10167;10196.643117490852;10334.436889162167;10480.592692841625;9119.680811171576;9752.512374381395;9804.803903921738;9820.45987470696;9848.261380396356 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,707 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: __startTimestamp1456421559584__endTimestamp 09:32:39 INFO - 2016-02-25 09:32:39,592 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5557.86 Median:5782.88 stddev:624.26 (10.8%) stddev-sans-first:600.23 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: Values: 3287.8 3377.7 3401.3 3406.8 3425.8 5534.7 5638.8 5663.5 5664.7 5695.3 5655.6 5678.4 5692.4 5692.6 5701.3 4856.7 5104.8 5145.6 5203.2 5218.1 4879.7 5022.4 5046.1 5046.7 5053.2 5015.2 5017.4 5025.4 5088.5 5109.1 5031.6 5050.2 5052.5 5078.4 5081.1 4923.6 4950.4 4974.0 4979.1 5049.5 5318.7 5392.1 5400.7 5405.3 5429.4 5973.8 6004.5 6052.9 6058.8 6068.4 5792.5 5873.0 5998.5 6071.1 6100.2 5568.3 5579.0 5581.2 5588.4 5589.5 6032.7 6043.6 6045.3 6045.7 6047.5 5620.9 5686.3 5793.0 5868.0 5938.5 5961.5 6036.9 6038.7 6042.8 6047.2 5974.2 6001.7 6006.6 6016.4 6019.1 5838.1 5877.8 5962.2 5994.1 5999.9 4578.3 4613.9 4633.1 4640.7 4667.1 3948.2 4142.4 4156.2 4166.4 4174.7 5508.9 5555.3 5572.2 5594.7 5630.5 5981.7 5984.4 5985.9 5989.2 5996.8 5662.2 5669.3 5670.3 5670.7 5672.2 5005.4 5042.5 5115.9 5129.9 5143.0 6234.8 6240.1 6241.1 6242.6 6247.3 6091.1 6092.7 6092.9 6095.9 6096.7 6001.2 6069.2 6143.1 6171.8 6182.4 5960.1 6001.6 6003.6 6008.6 6009.3 5699.2 6057.7 6099.0 6113.1 6126.2 5719.8 5774.8 5782.9 5786.9 5799.6 5885.1 5922.1 5938.3 5939.1 5942.7 5551.5 5658.8 5819.1 5820.6 5822.3 5879.5 5947.6 5966.3 5975.7 5979.0 5808.8 5846.6 5849.0 5851.3 5852.0 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45548.65 Median:32864.17 stddev:75993.30 (231.2%) stddev-sans-first:76131.56 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: Values: 604.4 638.4 640.4 644.1 702.3 30900.7 31561.5 31602.4 38279.6 38397.2 42724.6 43200.0 43408.1 43450.6 43526.0 8931.3 9121.6 9125.5 9126.1 9127.6 8192.7 8396.5 10495.8 10752.6 10786.8 10528.5 10656.6 10664.4 10665.2 10667.5 10515.6 10618.9 10621.4 10627.1 10647.3 10379.0 10549.8 10569.8 10575.6 10580.8 39762.4 40131.6 40200.4 40320.4 40326.8 3747.6 3769.4 3828.4 4218.3 4281.4 30898.4 31130.5 31282.4 31369.2 31768.7 4198.7 4212.9 4217.5 4314.9 4349.4 269517.0 270649.0 272278.0 272547.0 413807.0 48345.3 61226.0 62814.0 62892.0 63039.4 57306.1 58076.0 58113.1 58118.0 58143.7 16033.1 16254.5 16292.7 16298.1 16298.4 12295.8 12458.2 12470.6 12476.9 12542.0 19006.6 19248.1 19403.0 19414.4 19472.1 7707.7 7865.3 7894.7 7936.2 8019.0 938.4 1049.9 1054.2 1086.7 1109.5 25113.5 25384.5 25429.5 25740.7 28997.3 1002.7 1028.0 1063.1 1091.7 1139.7 13192.5 13855.4 15530.5 15630.0 16194.3 339478.0 344107.0 344155.0 344259.0 344484.0 53449.2 53869.0 53977.0 54098.0 54238.5 1238.4 1288.9 1364.3 1437.3 1449.4 27427.3 28199.9 28283.6 29143.3 29666.1 42884.1 43291.6 43301.0 43379.8 43385.8 202988.0 203584.0 203646.0 204024.0 204429.0 30370.4 31127.8 31140.5 31322.4 31333.0 41363.0 43397.0 43877.6 44041.3 44231.3 35355.8 35565.8 35964.0 36256.6 36576.6 35610.6 35630.0 36076.6 36400.8 36460.3 34532.8 35149.3 35328.6 35355.1 35375.5 31779.7 32506.0 33222.3 33327.0 33449.7 24948.9 25424.2 26439.6 27777.2 28087.9 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,593 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31497.67 Median:2432.00 stddev:102542.54 (4216.4%) stddev-sans-first:102803.59 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: Values: 389.5 389.7 392.1 393.6 395.2 14734.9 15252.7 16685.2 18457.0 18477.4 17224.2 17330.8 17348.5 17491.1 17898.5 6173.8 6338.6 6350.1 6350.9 6353.3 4603.1 4714.1 6232.3 6881.2 7040.5 5312.3 6921.3 6999.5 7013.2 7028.0 5446.2 5447.8 5450.5 5458.6 5485.8 5265.3 5287.8 5417.3 5421.7 5428.6 12882.6 12900.2 13162.7 13425.2 13478.9 2235.6 2310.9 2381.5 2422.7 2441.3 9810.1 10096.5 10757.6 10971.5 14489.9 1760.6 1872.2 1911.6 1929.2 1974.0 61451.0 62863.1 63235.0 63580.4 64824.7 10086.3 10296.9 10318.9 10338.7 10366.7 12713.8 12726.7 12742.8 12765.2 12838.5 7776.1 7783.3 7798.3 7824.9 7841.0 7353.7 7514.1 7566.5 7821.5 7900.6 3925.7 4192.5 4212.9 4240.3 4247.4 1691.2 1692.2 1757.3 1770.2 1774.3 920.8 926.5 991.2 1011.9 1063.1 15827.3 16882.8 17044.2 17204.1 17749.6 958.3 979.4 982.2 995.8 1010.1 9842.7 9998.8 10377.4 10528.5 10605.7 614547.0 622745.0 630322.0 630512.0 639996.0 82089.0 82606.6 85125.0 86050.1 87690.0 1143.7 1162.3 1162.6 1174.1 1178.4 15045.2 15689.4 16084.4 16318.3 16472.1 36866.4 37084.4 37877.8 38169.0 38986.0 45273.0 45600.5 47311.1 47318.3 50492.2 9560.4 9589.7 9799.2 10089.5 10095.0 11372.6 11427.0 13011.2 15374.5 17538.0 14481.7 14583.2 14711.0 14717.6 15213.8 13943.3 14525.3 14533.3 14801.3 14836.8 14113.8 14786.2 14981.3 15103.5 15328.2 9587.9 9628.0 9681.6 9806.5 9835.2 10295.7 10338.1 10730.5 15295.6 15542.2 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5529.40 Median:6785.69 stddev:2895.32 (42.7%) stddev-sans-first:2887.62 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: Values: 1502.6 1580.4 1585.5 1648.6 1709.5 7745.5 7911.4 7916.4 7932.1 7948.9 6990.7 7203.6 7209.5 7563.6 7977.4 2258.9 2281.5 2311.3 2323.9 2324.1 2271.1 2309.6 2312.9 2313.2 2313.3 1971.6 2002.9 2217.7 2288.7 2290.1 2270.0 2274.0 2307.3 2307.9 2309.5 2300.0 2328.5 2335.9 2340.6 2356.4 7816.8 7934.3 7942.3 7949.2 7955.2 4732.8 4844.2 4848.3 4850.9 4855.2 3002.3 3017.0 3033.3 3040.8 3058.4 1051.6 1072.2 1103.7 1110.2 1110.2 10655.7 10795.0 10801.2 10816.3 10885.9 4074.0 4125.8 4164.8 4186.2 4218.6 8923.9 8925.1 8931.9 8935.3 8935.5 4701.6 4788.5 4790.0 4790.6 4790.9 4425.1 4427.4 4432.8 4434.8 4434.8 3425.8 3452.1 3475.9 3495.8 3511.3 984.4 1044.5 1048.4 1050.4 1065.3 1030.1 1109.8 1132.4 1245.8 1344.6 7499.3 7568.8 7671.7 7673.6 7697.5 629.8 640.3 642.1 643.2 779.8 5849.1 5870.1 5969.5 5980.7 5982.6 9249.3 9430.2 9456.4 9464.6 9465.5 9279.4 9299.6 9451.6 9458.5 9466.7 7303.6 7363.9 7439.8 7453.1 7458.4 7507.9 7567.2 7593.8 7597.8 7600.4 8284.2 8639.3 8675.0 8691.0 8697.5 8652.1 8768.1 8903.1 8909.0 8913.4 7064.9 7091.1 7149.9 7155.5 7207.5 7519.4 7547.3 7548.5 7559.0 7571.2 6426.8 7248.1 7278.0 7283.7 7427.7 6935.7 6968.8 6991.6 7021.5 7195.7 7162.6 7203.1 7207.5 7212.5 7218.9 6770.5 6777.8 6797.6 6804.6 6806.9 6793.6 6889.8 6935.9 6941.9 6955.3 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,595 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5458.93 Median:6259.64 stddev:1647.89 (26.3%) stddev-sans-first:1607.19 09:32:39 INFO - 2016-02-25 09:32:39,596 DEBUG : BROWSER_OUTPUT: Values: 345.4 354.4 357.4 359.6 362.3 6111.6 6157.5 6161.5 6166.7 6179.6 5842.9 5931.4 6000.6 6080.8 6106.4 4692.1 4693.8 4698.2 4720.2 4723.7 4442.4 4682.6 4692.1 4709.7 4720.5 4542.3 4555.9 4634.9 4669.0 4681.4 4654.4 4656.0 4662.9 4668.1 4679.5 4117.0 4126.0 4126.9 4128.4 4135.5 5701.8 5784.5 5801.3 5801.4 5801.6 5399.8 5423.2 5428.0 5432.2 5433.4 6486.4 6488.6 6495.7 6497.4 6505.0 4519.1 4527.8 4530.3 4530.3 4532.6 6545.5 6609.2 6666.5 6690.5 6694.9 4623.0 4629.3 4630.1 4634.4 4636.9 4684.9 4686.3 4686.8 4688.7 4690.9 5391.0 5397.3 5400.6 5406.1 5410.3 6334.8 6519.8 6523.2 6528.1 6530.2 1635.5 1649.2 1653.4 1655.0 1656.3 1108.3 1111.0 1113.3 1133.5 1134.9 6335.3 6345.8 6359.0 6366.1 6368.6 6549.3 6746.1 6754.5 6762.3 6762.8 6281.7 6321.3 6384.1 6420.5 6431.4 3620.9 3666.9 3669.1 3669.5 3672.2 6849.3 6984.1 6985.6 6990.0 6996.4 6939.5 6967.2 6968.1 6970.6 6979.4 6970.7 6996.4 7012.6 7017.4 7019.6 6642.4 6799.5 6799.9 6816.7 6817.6 6716.0 6718.1 6721.0 6731.9 6733.5 7011.2 7022.0 7022.7 7024.6 7035.3 4512.8 4646.9 4658.1 4681.0 4681.7 6742.1 6747.2 6752.6 6752.8 6754.9 6539.4 6543.2 6544.3 6551.0 6555.8 6442.5 6551.5 6551.6 6559.7 6566.0 6458.6 6460.1 6460.5 6463.9 6465.9 6554.1 6584.0 6589.6 6598.6 6601.5 6237.6 6318.5 6370.2 6404.1 6416.1 09:32:39 INFO - 2016-02-25 09:32:39,596 DEBUG : BROWSER_OUTPUT: 09:32:39 INFO - 2016-02-25 09:32:39,596 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14128.93 Median:51563.74 stddev:16955.18 (32.9%) stddev-sans-first:16973.13 09:32:39 INFO - 2016-02-25 09:32:39,597 DEBUG : BROWSER_OUTPUT: Values: 784.8 796.0 849.8 854.5 888.0 13124.0 13536.3 13539.3 13542.7 13609.8 12780.8 12868.3 12868.4 12889.8 12915.4 689.7 696.1 803.1 805.4 815.5 808.9 810.4 813.1 816.4 818.3 721.7 846.1 849.8 851.3 852.2 723.5 844.1 848.1 850.1 853.9 627.0 627.7 629.5 631.9 632.0 13295.9 13452.6 13493.9 13572.3 13597.4 11715.9 11977.5 12015.3 12053.8 12206.8 10158.9 10263.3 10322.8 10325.6 10360.0 3651.0 3672.7 3697.8 3711.5 3714.5 51022.2 51485.7 51544.7 51582.7 51694.7 9513.5 9813.8 9850.9 9876.5 9930.2 17661.6 17671.3 17676.6 17677.2 17683.9 7371.3 7412.0 7419.7 7509.0 7608.3 4298.1 4338.2 4421.0 4440.7 4444.8 3065.5 3097.5 3099.3 3101.6 3102.8 563.4 568.5 571.3 574.7 576.9 526.1 582.7 583.5 608.8 652.6 8371.4 8446.9 8552.5 8616.8 8773.3 518.3 537.1 592.5 605.3 622.6 3676.6 3728.7 3824.9 3857.5 3887.7 60300.4 60356.8 60371.1 60497.0 60559.4 52907.7 54088.2 54275.7 54816.5 54967.2 48177.0 48278.5 49432.5 49575.3 50425.0 7090.9 7251.0 7346.6 7414.9 7473.0 37221.8 37682.6 38862.6 38895.4 38965.8 43779.6 44043.6 45841.5 47790.3 48249.5 9744.6 9885.2 9902.0 10091.9 10337.1 11259.9 12448.5 12628.9 12797.9 12944.8 10926.7 11248.4 11501.1 11518.8 11525.1 10657.7 11056.4 11359.0 11420.0 11514.5 9310.2 9541.9 9744.9 10709.7 10794.3 9666.5 10167.0 10196.6 10334.4 10480.6 9119.7 9752.5 9804.8 9820.5 9848.3 09:32:39 INFO - 2016-02-25 09:32:39,597 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:32:39 INFO - 2016-02-25 09:32:39,597 DEBUG : BROWSER_OUTPUT: 09:32:40 INFO - 2016-02-25 09:32:40,227 INFO : Browser exited with error code: 0 09:32:40 INFO - 2016-02-25 09:32:40,243 INFO : Completed test dromaeo_css (00:18:30) 09:32:40 INFO - 2016-02-25 09:32:40,243 INFO : Starting test kraken 09:32:40 INFO - 2016-02-25 09:32:40,243 DEBUG : operating with platform_type : linux_ 09:32:40 INFO - 2016-02-25 09:32:40,243 INFO : Initialising browser for kraken test... 09:32:40 INFO - 2016-02-25 09:32:40,249 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKnBqio/profile http://localhost:41106/getInfo.html 09:32:43 INFO - 2016-02-25 09:32:43,949 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:32:43 INFO - 2016-02-25 09:32:43,949 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:32:43 INFO - 2016-02-25 09:32:43,949 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:32:43 INFO - 2016-02-25 09:32:43,949 DEBUG : BROWSER_OUTPUT: __metrics 09:32:44 INFO - 2016-02-25 09:32:44,118 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:32:44 INFO - 2016-02-25 09:32:44,891 INFO : Browser initialized. 09:32:44 INFO - 2016-02-25 09:32:44,892 INFO : Running cycle 1/1 for kraken test... 09:32:44 INFO - 2016-02-25 09:32:44,892 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKnBqio/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:32:56 INFO - 2016-02-25 09:32:56,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 134152192 09:32:56 INFO - 2016-02-25 09:32:56,507 DEBUG : BROWSER_OUTPUT: 09:32:58 INFO - 2016-02-25 09:32:58,961 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41106/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:59 INFO - 2016-02-25 09:32:59,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 165724160 09:32:59 INFO - 2016-02-25 09:32:59,528 DEBUG : BROWSER_OUTPUT: 09:33:02 INFO - 2016-02-25 09:33:02,108 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41106/tests/kraken/driver.html?testName=audio-dft) 09:33:02 INFO - 2016-02-25 09:33:02,670 DEBUG : BROWSER_OUTPUT: RSS: Main: 164945920 09:33:02 INFO - 2016-02-25 09:33:02,670 DEBUG : BROWSER_OUTPUT: 09:33:05 INFO - 2016-02-25 09:33:05,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41106/tests/kraken/driver.html?testName=audio-fft) 09:33:06 INFO - 2016-02-25 09:33:06,017 DEBUG : BROWSER_OUTPUT: RSS: Main: 161243136 09:33:06 INFO - 2016-02-25 09:33:06,017 DEBUG : BROWSER_OUTPUT: 09:33:07 INFO - 2016-02-25 09:33:07,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41106/tests/kraken/driver.html?testName=audio-oscillator) 09:33:08 INFO - 2016-02-25 09:33:08,561 DEBUG : BROWSER_OUTPUT: RSS: Main: 160317440 09:33:08 INFO - 2016-02-25 09:33:08,561 DEBUG : BROWSER_OUTPUT: 09:33:10 INFO - 2016-02-25 09:33:10,755 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41106/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:11 INFO - 2016-02-25 09:33:11,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 160198656 09:33:11 INFO - 2016-02-25 09:33:11,316 DEBUG : BROWSER_OUTPUT: 09:33:14 INFO - 2016-02-25 09:33:14,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41106/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:14 INFO - 2016-02-25 09:33:14,733 DEBUG : BROWSER_OUTPUT: RSS: Main: 159604736 09:33:14 INFO - 2016-02-25 09:33:14,734 DEBUG : BROWSER_OUTPUT: 09:33:18 INFO - 2016-02-25 09:33:18,045 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41106/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:18 INFO - 2016-02-25 09:33:18,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 160292864 09:33:18 INFO - 2016-02-25 09:33:18,611 DEBUG : BROWSER_OUTPUT: 09:33:21 INFO - 2016-02-25 09:33:21,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41106/tests/kraken/driver.html?testName=json-parse-financial) 09:33:21 INFO - 2016-02-25 09:33:21,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 159580160 09:33:21 INFO - 2016-02-25 09:33:21,900 DEBUG : BROWSER_OUTPUT: 09:33:23 INFO - 2016-02-25 09:33:23,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41106/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:24 INFO - 2016-02-25 09:33:24,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 167157760 09:33:24 INFO - 2016-02-25 09:33:24,011 DEBUG : BROWSER_OUTPUT: 09:33:25 INFO - 2016-02-25 09:33:25,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:26 INFO - 2016-02-25 09:33:26,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 166182912 09:33:26 INFO - 2016-02-25 09:33:26,372 DEBUG : BROWSER_OUTPUT: 09:33:28 INFO - 2016-02-25 09:33:28,336 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:28 INFO - 2016-02-25 09:33:28,905 DEBUG : BROWSER_OUTPUT: RSS: Main: 171192320 09:33:28 INFO - 2016-02-25 09:33:28,906 DEBUG : BROWSER_OUTPUT: 09:33:31 INFO - 2016-02-25 09:33:31,261 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:31 INFO - 2016-02-25 09:33:31,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 175833088 09:33:31 INFO - 2016-02-25 09:33:31,834 DEBUG : BROWSER_OUTPUT: 09:33:34 INFO - 2016-02-25 09:33:34,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:34 INFO - 2016-02-25 09:33:34,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 176766976 09:33:34 INFO - 2016-02-25 09:33:34,915 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41106/tests/kraken/driver.html?testName=ai-astar) 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: RSS: Main: 235089920 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:33:36 INFO - 2016-02-25 09:33:36,827 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;112;104;110;112;110;102;109;111;110 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;123;148;164;123;148;158;122;184;121 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;161;162;159;159;161;160;162;161;159 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;148;72;73;71;72;145;71 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;143;91;92;92;141;90;93;134 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;110;110 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;145;146;145;145;145;146;145 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;95;95;96;95;95;95 09:33:36 INFO - 2016-02-25 09:33:36,828 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;56;56;57;57;57;56;56;56;54 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;64;65;64;65;64;64;64 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;75;78;78;76;77;79;77;77;77 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;115;111;114;112;121;113;113;111;114;120 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;145;143;143;144;145;132;148;144;143;142 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;64;63;65;63;62;66;64;65 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:33:36 INFO - 2016-02-25 09:33:36,829 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: __startTimestamp1456421616821__endTimestamp 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,830 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:3.31 (3.0%) stddev-sans-first:3.52 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: Values: 109.0 112.0 104.0 110.0 112.0 110.0 102.0 109.0 111.0 110.0 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.90 Median:148.00 stddev:21.44 (14.5%) stddev-sans-first:22.69 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: Values: 148.0 123.0 148.0 164.0 123.0 148.0 158.0 122.0 184.0 121.0 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.24 09:33:36 INFO - 2016-02-25 09:33:36,831 DEBUG : BROWSER_OUTPUT: Values: 159.0 161.0 162.0 159.0 159.0 161.0 160.0 162.0 161.0 159.0 09:33:36 INFO - 2016-02-25 09:33:36,832 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,832 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.50 Median:71.00 stddev:31.64 (44.6%) stddev-sans-first:33.06 09:33:36 INFO - 2016-02-25 09:33:36,832 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 148.0 72.0 73.0 71.0 72.0 145.0 71.0 09:33:36 INFO - 2016-02-25 09:33:36,832 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:92.00 stddev:22.93 (24.9%) stddev-sans-first:23.81 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 143.0 91.0 92.0 92.0 141.0 90.0 93.0 134.0 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 09:33:36 INFO - 2016-02-25 09:33:36,833 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:0.92 (1.6%) stddev-sans-first:0.93 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: Values: 57.0 56.0 56.0 57.0 57.0 57.0 56.0 56.0 56.0 54.0 09:33:36 INFO - 2016-02-25 09:33:36,834 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 64.0 65.0 64.0 65.0 64.0 64.0 64.0 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.00 stddev:1.15 (1.5%) stddev-sans-first:1.17 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: Values: 76.0 75.0 78.0 78.0 76.0 77.0 79.0 77.0 77.0 77.0 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:114.00 stddev:3.47 (3.0%) stddev-sans-first:3.67 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: Values: 115.0 111.0 114.0 112.0 121.0 113.0 113.0 111.0 114.0 120.0 09:33:36 INFO - 2016-02-25 09:33:36,835 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.90 Median:144.00 stddev:4.18 (2.9%) stddev-sans-first:4.36 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: Values: 145.0 143.0 143.0 144.0 145.0 132.0 148.0 144.0 143.0 142.0 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:1.15 (1.8%) stddev-sans-first:1.22 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 63.0 65.0 63.0 62.0 66.0 64.0 65.0 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:33:36 INFO - 2016-02-25 09:33:36,836 DEBUG : BROWSER_OUTPUT: 09:33:37 INFO - 2016-02-25 09:33:37,467 INFO : Browser exited with error code: 0 09:33:37 INFO - 2016-02-25 09:33:37,516 INFO : Completed test kraken (00:00:57) 09:33:37 INFO - 2016-02-25 09:33:37,516 INFO : Starting test v8_7 09:33:37 INFO - 2016-02-25 09:33:37,516 DEBUG : operating with platform_type : linux_ 09:33:37 INFO - 2016-02-25 09:33:37,516 INFO : Initialising browser for v8_7 test... 09:33:37 INFO - 2016-02-25 09:33:37,522 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpN2nTRZ/profile http://localhost:41106/getInfo.html 09:33:41 INFO - 2016-02-25 09:33:41,041 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:33:41 INFO - 2016-02-25 09:33:41,041 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:33:41 INFO - 2016-02-25 09:33:41,041 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:33:41 INFO - 2016-02-25 09:33:41,041 DEBUG : BROWSER_OUTPUT: __metrics 09:33:41 INFO - 2016-02-25 09:33:41,186 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:42 INFO - 2016-02-25 09:33:42,271 INFO : Browser initialized. 09:33:42 INFO - 2016-02-25 09:33:42,272 INFO : Running cycle 1/1 for v8_7 test... 09:33:42 INFO - 2016-02-25 09:33:42,272 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpN2nTRZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:33:53 INFO - 2016-02-25 09:33:53,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 133967872 09:33:53 INFO - 2016-02-25 09:33:53,938 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41106/tests/v8_7/run.html (next: http://localhost:41106/tests/v8_7/run.html) 09:34:15 INFO - 2016-02-25 09:34:15,531 DEBUG : BROWSER_OUTPUT: RSS: Main: 423862272 09:34:15 INFO - 2016-02-25 09:34:15,531 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,531 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:34:15 INFO - 2016-02-25 09:34:15,531 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:34:15 INFO - 2016-02-25 09:34:15,531 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |0;Richards;166.333998669328 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.18004625041294 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 09:34:15 INFO - 2016-02-25 09:34:15,532 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.969696969697 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: |5;Earley;543.773790103317 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: |6;Boyer;10510.416666666666 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: |7;RegExp;24261.904761904763 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: |8;Splay;525.7623554153523 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 09:34:15 INFO - 2016-02-25 09:34:15,533 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:34:15 INFO - 2016-02-25 09:34:15,534 DEBUG : BROWSER_OUTPUT: __startTimestamp1456421655530__endTimestamp 09:34:15 INFO - 2016-02-25 09:34:15,534 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,534 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:34:15 INFO - 2016-02-25 09:34:15,534 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:34:15 INFO - 2016-02-25 09:34:15,534 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.33 Median:166.33 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: Values: 166.3 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.18 Median:165.18 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: Values: 165.2 09:34:15 INFO - 2016-02-25 09:34:15,535 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: Values: 278.8 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: Values: 5208.3 09:34:15 INFO - 2016-02-25 09:34:15,536 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.97 Median:946.97 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: Values: 947.0 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.77 Median:543.77 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: Values: 543.8 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10510.42 Median:10510.42 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,537 DEBUG : BROWSER_OUTPUT: Values: 10510.4 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24261.90 Median:24261.90 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: Values: 24261.9 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:525.76 Median:525.76 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: Values: 525.8 09:34:15 INFO - 2016-02-25 09:34:15,538 DEBUG : BROWSER_OUTPUT: 09:34:15 INFO - 2016-02-25 09:34:15,539 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 09:34:15 INFO - 2016-02-25 09:34:15,539 DEBUG : BROWSER_OUTPUT: Values: 4830.9 09:34:15 INFO - 2016-02-25 09:34:15,539 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:34:15 INFO - 2016-02-25 09:34:15,539 DEBUG : BROWSER_OUTPUT: 09:34:16 INFO - 2016-02-25 09:34:16,223 INFO : Browser exited with error code: 0 09:34:16 INFO - 2016-02-25 09:34:16,238 INFO : Completed test v8_7 (00:00:38) 09:34:16 INFO - 2016-02-25 09:34:16,582 INFO : Completed test suite (00:20:06) 09:34:16 INFO - 2016-02-25 09:34:16,582 DEBUG : Working with test: dromaeo_css 09:34:16 INFO - 2016-02-25 09:34:16,582 DEBUG : Generating results file: dromaeo_css 09:34:16 INFO - 2016-02-25 09:34:16,584 DEBUG : Working with test: kraken 09:34:16 INFO - 2016-02-25 09:34:16,584 DEBUG : Generating results file: kraken 09:34:16 INFO - 2016-02-25 09:34:16,584 INFO : javascript benchmark 09:34:16 INFO - 2016-02-25 09:34:16,585 DEBUG : Working with test: v8_7 09:34:16 INFO - 2016-02-25 09:34:16,585 DEBUG : Generating results file: v8_7 09:34:16 INFO - 2016-02-25 09:34:16,585 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:34:16 INFO - 2016-02-25 09:34:16,641 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:34:16 INFO - 2016-02-25 09:34:16,641 DEBUG : process_Request line: dromaeo_css 7123.61 graph.html#tests=[[72,53,35]] 09:34:16 INFO - 2016-02-25 09:34:16,641 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:34:16 INFO - 2016-02-25 09:34:16,673 DEBUG : process_Request line: kraken 1474.40 graph.html#tests=[[232,53,35]] 09:34:16 INFO - 2016-02-25 09:34:16,673 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:34:16 INFO - 2016-02-25 09:34:16,735 DEBUG : process_Request line: v8_7 23280.84 graph.html#tests=[[230,53,35]] 09:34:16 INFO - 2016-02-25 09:34:16,737 INFO : javascript benchmark 09:34:16 INFO - 2016-02-25 09:34:16,740 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3287.819169945653, 3377.729781617471, 3401.3197360527884, 3406.795592264464, 3425.828708564571, 5534.667919924807, 5638.774449022039, 5663.490285874271, 5664.660120392779, 5695.316562012554, 5655.632383895044, 5678.375378708339, 5692.402297758737, 5692.601517893739, 5701.258836351269, 4856.660033797635, 5104.770285337168, 5145.614078944075, 5203.245529398235, 5218.112920803473, 4879.682820616648, 5022.372203474566, 5046.09170349337, 5046.747662616873, 5053.242013697938, 5015.197568389059, 5017.448080711119, 5025.422076461217, 5088.491150884919, 5109.131447653889, 5031.647784655075, 5050.242463630448, 5052.494750524955, 5078.4159821620615, 5081.085404627165, 4923.606111511071, 4950.405951285833, 4973.975130124362, 4979.053979743837, 5049.469805670388, 5318.707471089088, 5392.083345831218, 5400.675959442446, 5405.324334458192, 5429.4027656957705, 5973.820785376446, 6004.519638428916, 6052.909206361908, 6058.818235452944, 6068.393160683941, 5792.478676919055, 5872.970635146839, 5998.520118390518, 6071.089336599502, 6100.23747031621, 5568.331800184003, 5578.972105139448, 5581.2186293918885, 5588.385277619459, 5589.469000444951, 6032.728527216286, 6043.607165534227, 6045.274567051894, 6045.72794224261, 6047.516198704093, 5620.91568626471, 5686.2607860977805, 5792.9710351447975, 5867.970660146672, 5938.524918006549, 5961.493273071751, 6036.939630603725, 6038.698065096728, 6042.788502402394, 6047.213862197887, 5974.193483879708, 6001.699915004232, 6006.639601623917, 6016.42843929826, 6019.097135429721, 5838.095095260242, 5877.764889404376, 5962.165296858443, 5994.100884867305, 5999.850003749854, 4578.267477203632, 4613.930791038137, 4633.142868569258, 4640.6751527393435, 4667.113248482778, 3948.1511475033008, 4142.378643203544, 4156.189543039139, 4166.375043743463, 4174.728642638219, 5508.862278442991, 5555.250041244381, 5572.192032155314, 5594.692291923902, 5630.5495560355075, 5981.6710080945095, 5984.401559844068, 5985.940140598538, 5989.161517387569, 5996.790112346047, 5662.235598194274, 5669.262995810518, 5670.291213598301, 5670.744816483269, 5672.234248376499, 5005.449400565934, 5042.495750424928, 5115.8721031973755, 5129.897402051938, 5142.997115562504, 6234.781782637677, 6240.126382306481, 6241.126242326078, 6242.625442473466, 6247.344028876993, 6091.147239386578, 6092.725827337692, 6092.878142437038, 6095.878082438415, 6096.664683442275, 6001.159837622824, 6069.2110025696375, 6143.139960405458, 6171.753129874754, 6182.381761823782, 5960.105984102246, 6001.579889407874, 6003.609765365237, 6008.639481631116, 6009.278886533644, 5699.173619825185, 6057.697115144313, 6098.969505152446, 6113.083037544404, 6126.172966649446, 5719.828405147852, 5774.82675519735, 5782.884342313046, 5786.884262314646, 5799.623024503394, 5885.087811389241, 5922.052471604523, 5938.287405511366, 5939.07944268639, 5942.6731529766275, 5551.500364967173, 5658.773649053992, 5819.0689489681445, 5820.592558520862, 5822.301323841166, 5879.5002424794375, 5947.613405128653, 5966.284045914518, 5975.671338076274, 5979.043353063489, 5808.76764929415, 5846.56150788684, 5848.970755146197, 5851.327097383685, 5852.0], "unit": "ms", "name": "dojo.html", "value": 5517.359500953132}, {"lowerIsBetter": false, "replicates": [604.4378727783163, 638.3839594791027, 640.3980258556957, 644.1400730025416, 702.2696395748419, 30900.690993090062, 31561.526577101333, 31602.36795264096, 38279.617203827955, 38397.232055358945, 42724.57275427245, 43200.0, 43408.13183736323, 43450.56549434505, 43526.0, 8931.285497160212, 9121.589528471202, 9125.498097604663, 9126.087391260853, 9127.589258483384, 8192.713255036075, 8396.496210227375, 10495.842562361571, 10752.569897204103, 10786.838197427045, 10528.473576321192, 10656.627018054369, 10664.360138391683, 10665.200109991743, 10667.466626668675, 10515.631952881613, 10618.893811061867, 10621.415822129818, 10627.096696780784, 10647.30792498485, 10379.0, 10549.789004219872, 10569.78860422795, 10575.576976920915, 10580.841287380696, 39762.403563946566, 40131.59868401337, 40200.39699404511, 40320.39519407211, 40326.798366007984, 3747.5502939647145, 3769.3968964965748, 3828.444875493066, 4218.261804184286, 4281.443412356373, 30898.382032359226, 31130.533042004387, 31282.374352512823, 31369.215769605715, 31768.682313176803, 4198.685098617585, 4212.873613791591, 4217.514110320212, 4314.913701725979, 4349.391085248068, 269517.0, 270649.0, 272278.0, 272547.0, 413807.0, 48345.27482087771, 61226.0, 62814.0, 62892.0, 63039.36960630335, 57306.140407893916, 58076.0, 58113.128303075486, 58118.0, 58143.709281453324, 16033.11817850006, 16254.51236462908, 16292.674146517003, 16298.10360430164, 16298.42955496552, 12295.754084918251, 12458.190217635825, 12470.563530276579, 12476.875231247572, 12542.0, 19006.61986760257, 19248.133833977503, 19403.0, 19414.417567473, 19472.123754431086, 7707.691692332244, 7865.252800983897, 7894.6842126314305, 7936.246056624612, 8019.0, 938.432248489654, 1049.9055085042382, 1054.2093429927554, 1086.684861390188, 1109.5062697099875, 25113.497730045663, 25384.492310153695, 25429.491410171693, 25740.742592573835, 28997.275068123043, 1002.6891663584312, 1027.9794404111876, 1063.0963680871353, 1091.7161538000178, 1139.6865861888048, 13192.47230110804, 13855.37650805696, 15530.456434024754, 15630.0, 16194.271257793192, 339478.0, 344107.0, 344155.0, 344259.0, 344484.0, 53449.198262026104, 53869.0, 53977.0, 54098.0, 54238.457615423344, 1238.4427007846505, 1288.9484420623257, 1364.3178410794603, 1437.2741765408612, 1449.4202319072244, 27427.31431714223, 28199.859000704866, 28283.57574636341, 29143.271418213866, 29666.110016699535, 42884.1423171541, 43291.56708432875, 43301.0, 43379.78310108429, 43385.78307108444, 202988.0, 203584.0, 203646.0, 204024.0, 204429.0, 30370.392592147593, 31127.84436077805, 31140.532892006184, 31322.373552528366, 31333.0, 41363.0, 43397.0, 43877.56122438863, 44041.339379909965, 44231.33652995143, 35355.82322088476, 35565.82217088898, 35964.0, 36256.63743362533, 36576.63423365732, 35610.64389356073, 35630.0, 36076.639233607326, 36400.81799590985, 36460.27079458449, 34532.82733586316, 35149.29701405906, 35328.64671353254, 35355.116122097155, 35375.46936796002, 31779.682203177672, 32506.0, 33222.33555328928, 33327.0, 33449.66550334466, 24948.875255623607, 25424.237272881845, 26439.603405948543, 27777.166684999484, 28087.859560702884], "unit": "ms", "name": "ext.html", "value": 18956.211142874323}, {"lowerIsBetter": false, "replicates": [389.51505375807125, 389.66683485619797, 392.0707922224327, 393.62802151966395, 395.20958083832335, 14734.926325368386, 15252.694946101072, 16685.16574171291, 18457.0, 18477.445676629704, 17224.224909879053, 17330.82669173308, 17348.479545613653, 17491.125443727826, 17898.463046108638, 6173.783917562898, 6338.556301058928, 6350.079238510413, 6350.936490635081, 6353.332900045498, 4603.079384123172, 4714.080754252922, 6232.345603711613, 6881.208661003973, 7040.47196460265, 5312.2828418163635, 6921.307869213064, 6999.545029573087, 7013.228544860087, 7027.964860175692, 5446.183072539131, 5447.754851031713, 5450.509454149106, 5458.590605704588, 5485.835424937239, 5265.262863199156, 5287.841364759025, 5417.26866872971, 5421.728913554307, 5428.5928555358505, 12882.613521594367, 12900.22598644075, 13162.736745265041, 13425.194488330633, 13478.932605337008, 2235.6422972324513, 2310.8844557772045, 2381.452265978817, 2422.7456117107627, 2441.2554170977846, 9810.117089461912, 10096.545655445523, 10757.569697212102, 10971.451427428676, 14489.927550362287, 1760.6390689908537, 1872.2136702584946, 1911.5603411215359, 1929.2283086765267, 1973.9506512337164, 61451.0, 62863.05705414422, 63235.0, 63580.36419635791, 64824.675876620786, 10086.344387614929, 10296.897031029745, 10318.896811031944, 10338.689839304832, 10366.740831479274, 12713.809292860615, 12726.745465090831, 12742.80885786714, 12765.234085954688, 12838.48646054166, 7776.144624091346, 7783.260590243918, 7798.298153166243, 7824.92175078242, 7840.960795195988, 7353.742619008309, 7514.098308202942, 7566.508176968479, 7821.452498325063, 7900.644470998819, 3925.7448265862627, 4192.517860446088, 4212.936805947913, 4240.300350442142, 4247.4265974093105, 1691.196681576266, 1692.1877498800645, 1757.314647287559, 1770.2034084661695, 1774.325756212631, 920.7513971227731, 926.5321012888448, 991.2218908157062, 1011.8735158105237, 1063.1016790811752, 15827.287772050286, 16882.831171688125, 17044.233009514603, 17204.1397930103, 17749.556261093578, 958.2717134977468, 979.3627175324831, 982.1946004276567, 995.7510622344414, 1010.0808264479289, 9842.704718858446, 9998.750031249132, 10377.377357358582, 10528.473576321154, 10605.681829545127, 614547.0, 622745.0, 630322.0, 630512.0, 639996.0, 82089.0, 82606.58696706599, 85125.0, 86050.13949860547, 87690.0, 1143.7369405036889, 1162.2503485252134, 1162.610525473959, 1174.0900801878443, 1178.4166837415535, 15045.172515511314, 15689.372425102869, 16084.43704470338, 16318.347266109222, 16472.09403482772, 36866.44700329444, 37084.44373334348, 37877.81061094677, 38169.0, 38986.0, 45273.0, 45600.54399455963, 47311.05377892354, 47318.29022564596, 50492.24261636152, 9560.378575392577, 9589.664361747306, 9799.216062714822, 10089.545970431494, 10095.040971107734, 11372.578427696539, 11427.0, 13011.154274972096, 15374.538763837103, 17538.0, 14481.710365792414, 14583.19792411385, 14711.0, 14717.632059198606, 15213.77179342333, 13943.30283485842, 14525.27373631293, 14533.273336333352, 14801.333939972512, 14836.777448338067, 14113.788293175816, 14786.186759728318, 14981.250937453302, 15103.54689359321, 15328.156951367782, 9587.856182157133, 9628.0, 9681.564329605044, 9806.509674516388, 9835.164011058854, 10295.691129266133, 10338.12125969301, 10730.463476825971, 15295.617609559406, 15542.222888855738], "unit": "ms", "name": "jquery.html", "value": 9073.404848877895}, {"lowerIsBetter": false, "replicates": [1502.6168327076593, 1580.4231455518739, 1585.4609432792847, 1648.563130770346, 1709.4529750479844, 7745.496542724726, 7911.406644501657, 7916.406269529787, 7932.127465978738, 7948.880766788503, 6990.650467476631, 7203.6038017908995, 7209.495335326529, 7563.6218189090605, 7977.361811055116, 2258.9096436142577, 2281.4752606900424, 2311.2603966730653, 2323.94190145246, 2324.1168356024687, 2271.136967952176, 2309.6304591265402, 2312.8843557822124, 2313.2366319114735, 2313.2713195343413, 1971.5859669469428, 2002.9499262518407, 2217.6895234667077, 2288.6795848581214, 2290.0954123121355, 2269.9773002270094, 2274.0, 2307.3193407944614, 2307.8846057697133, 2309.549637820609, 2299.9655005174936, 2328.52265285616, 2335.871527066011, 2340.6254999200046, 2356.4462351347593, 7816.765497035098, 7934.285914267686, 7942.324902383304, 7949.244821741925, 7955.244251796128, 4732.83435079774, 4844.224924012176, 4848.321235027099, 4850.878728031792, 4855.223164293696, 3002.2944608017096, 3017.0, 3033.3326668132977, 3040.8175509469283, 3058.4494790937624, 1051.568856768729, 1072.1583556907867, 1103.7130346109989, 1110.1784679337313, 1110.21729680575, 10655.733606659818, 10795.0, 10801.189910756724, 10816.296940698829, 10885.891141088488, 4074.0, 4125.752454852658, 4164.75011499311, 4186.246475634415, 4218.641415479658, 8923.910760892439, 8925.107489251022, 8931.866022009675, 8935.28517718581, 8935.463872167691, 4701.576858082789, 4788.497207793202, 4789.976050119797, 4790.592799612003, 4790.880227994328, 4425.137098265807, 4427.358033085185, 4432.778361081933, 4434.82260709568, 4434.84478043267, 3425.845836937344, 3452.067941655739, 3475.8957231283102, 3495.7727747696317, 3511.3328467591077, 984.3650845204803, 1044.5247412427327, 1048.433845723301, 1050.401271275381, 1065.2969040433277, 1030.0935177044153, 1109.7614012987246, 1132.416805345248, 1245.8006718924928, 1344.610063081715, 7499.2500749924575, 7568.8486230276185, 7671.693132274758, 7673.61631918413, 7697.499662521918, 629.7764293675771, 640.2509064394669, 642.0754114075716, 643.1510406263687, 779.8479296537121, 5849.064149736022, 5870.090136028923, 5969.492593129546, 5980.73086711099, 5982.551308651869, 9249.306302027375, 9430.198433133124, 9456.432614043179, 9464.621415143318, 9465.479398633004, 9279.39683920543, 9299.581518831668, 9451.574679139318, 9458.479783611967, 9466.668666596774, 7303.634818259172, 7363.926360736431, 7439.814004649818, 7453.105627324648, 7458.366038886756, 7507.887381689388, 7567.167611562613, 7593.772186834404, 7597.848043039219, 7600.429967752551, 8284.171582841667, 8639.308855291687, 8674.956625216833, 8691.0, 8697.478151310941, 8652.134786521297, 8768.079351667982, 8903.109689031304, 8908.955455222682, 8913.37606367574, 7064.858702826017, 7091.078159839188, 7149.9285007149265, 7155.463340249398, 7207.459440542085, 7519.43604229674, 7547.28300811422, 7548.509346892434, 7559.0, 7571.242875712384, 6426.807195784134, 7248.130224373109, 7278.0, 7283.7450689225625, 7427.665755040928, 6935.687894044881, 6968.825779355557, 6991.580505169706, 7021.473389495911, 7195.712171513081, 7162.641867906688, 7203.135623725187, 7207.459440541876, 7212.4590655699985, 7218.855622887618, 6770.492213084137, 6777.762778302736, 6797.5581587196675, 6804.591724496546, 6806.897896531457, 6793.626350550767, 6889.758858439931, 6935.895961560682, 6941.861162776817, 6955.304469553206], "unit": "ms", "name": "mootools.html", "value": 4474.6850665261345}, {"lowerIsBetter": false, "replicates": [345.3886620681394, 354.41875324467867, 357.3745944596955, 359.57749644168115, 362.2917196880098, 6111.6027458215185, 6157.476614487774, 6161.476274516672, 6166.691665416723, 6179.567430279876, 5842.941570584293, 5931.436513531207, 6000.609960352574, 6080.787172448965, 6106.389361063892, 4692.061587682477, 4693.765311734417, 4698.177818881699, 4720.221163508017, 4723.740194289313, 4442.444694413199, 4682.555157260055, 4692.085043416535, 4709.670323077386, 4720.504347043562, 4542.273236282195, 4555.931661025087, 4634.930476042845, 4669.042846216517, 4681.438227412716, 4654.371659825931, 4656.045510670305, 4662.860114196579, 4668.089722504113, 4679.532046795327, 4117.0, 4125.97937010316, 4126.896827579305, 4128.380742888561, 4135.462389889295, 5701.77192912283, 5784.508316793078, 5801.332846722638, 5801.419858014207, 5801.6228945118855, 5399.83800485986, 5423.159410291371, 5428.0, 5432.239486471857, 5433.429489903582, 6486.416222539947, 6488.643124628144, 6495.740170393178, 6497.350264973512, 6504.967475162642, 4519.118771839492, 4527.84152554659, 4530.275155975029, 4530.275155975029, 4532.592066713979, 6545.476361891037, 6609.206895172562, 6666.500012499082, 6690.498212634072, 6694.93305066948, 4622.9768851156205, 4629.305604159403, 4630.097131059411, 4634.374359461497, 4636.86089417318, 4684.882877928011, 4686.297055441711, 4686.835960741358, 4688.718676879398, 4690.859274221779, 5391.0, 5397.298351214317, 5400.594955378362, 5406.135018397116, 5410.296661433988, 6334.841628959313, 6519.837004074936, 6523.151990241238, 6528.118703974903, 6530.216374035052, 1635.4684727463623, 1649.2331066054342, 1653.3717187468685, 1655.0152659167775, 1656.2546853915546, 1108.2519299472824, 1110.9777804443866, 1113.2819331531116, 1133.5012594458412, 1134.9035331996872, 6335.271443783931, 6345.841353966188, 6359.0, 6366.076918846741, 6368.554201205964, 6549.279579246279, 6746.123004009545, 6754.493412993947, 6762.323767623198, 6762.830929226809, 6281.685915704196, 6321.304656487782, 6384.106225128486, 6420.486361091102, 6431.421172094534, 3620.8551657933913, 3666.926661466756, 3669.1377526281303, 3669.486271921933, 3672.1737609038287, 6849.315068493111, 6984.126984126984, 6985.615791131537, 6989.965050174716, 6996.370326670624, 6939.514234003571, 6967.233604303523, 6968.059311992816, 6970.616616086062, 6979.4416446684145, 6970.721171153198, 6996.440284777307, 7012.614306213207, 7017.438604911595, 7019.578825270398, 6642.43539299165, 6799.456043516606, 6799.93200067993, 6816.659167041727, 6817.5909445433435, 6716.0, 6718.0594716738715, 6720.966395167993, 6731.865362692816, 6733.494987876027, 7011.1586609607175, 7021.963630547289, 7022.719091236498, 7024.578525288499, 7035.261297563887, 4512.842050528216, 4646.930296045494, 4658.114958157939, 4680.953190468052, 4681.6722829401615, 6742.123523941857, 6747.156605424322, 6752.628605426748, 6752.831179220559, 6754.898676519954, 6539.378759017885, 6543.247526534509, 6544.280129185881, 6550.967245163934, 6555.836104097436, 6442.484601231984, 6551.5259066709605, 6551.6069035858, 6559.704813283318, 6565.967170164118, 6458.644774537445, 6460.095586617783, 6460.45086167681, 6463.870722585428, 6465.903011454926, 6554.14796076507, 6584.0, 6589.6375699336995, 6598.60408375499, 6601.537892347682, 6237.594556353823, 6318.462930650946, 6370.1718776558755, 6404.135441715495, 6416.101745755691], "unit": "ms", "name": "prototype.html", "value": 4927.5141825471255}, {"lowerIsBetter": false, "replicates": [784.8194915169514, 795.9840803183932, 849.8470275350435, 854.4873076154307, 887.9556022198889, 13124.0, 13536.323183840817, 13539.255340956244, 13542.729145417097, 13609.795853062213, 12780.808287875689, 12868.29224392658, 12868.356582170902, 12889.80665290019, 12915.418806153722, 689.7378995981545, 696.1333140240408, 803.0804728585751, 805.4442434720054, 815.5473712089788, 808.8544062068826, 810.4367464612097, 813.0771574263224, 816.4488969945263, 818.3494122172887, 721.7185297733888, 846.0946786937978, 849.7578190215789, 851.2679095977454, 852.228732996639, 723.5477826358526, 844.0926004545139, 848.088305072047, 850.0776657326808, 853.894207001934, 627.0468887291337, 627.6830200748639, 629.5247088448239, 631.9336469670665, 632.032989526026, 13295.86704132956, 13452.596422107352, 13493.865061349457, 13572.321383930863, 13597.388117534734, 11715.88284117165, 11977.461014254379, 12015.339156346397, 12053.819192712117, 12206.755864882742, 10158.949205254, 10263.281570290083, 10322.812876519221, 10325.587894757808, 10360.0, 3650.9817450912574, 3672.6878215351726, 3697.759645623026, 3711.536057992751, 3714.53568303962, 51022.234666480035, 51485.74257128728, 51544.74227628875, 51582.74208628933, 51694.741526292506, 9513.476758778334, 9813.754656133511, 9850.852237216448, 9876.506174691236, 9930.205583553443, 17661.55846103832, 17671.29314827418, 17676.558086047695, 17677.20452579637, 17683.911580442273, 7371.299726525972, 7412.0364352633815, 7419.740309089156, 7508.962455187799, 7608.31525162738, 4298.118885628439, 4338.175746608134, 4420.955790442054, 4440.711353761995, 4444.799984000728, 3065.4788685923445, 3097.4734295169874, 3099.3491366812773, 3101.6278046634097, 3102.7672924530752, 563.3605857351935, 568.4713216708418, 571.2973043156941, 574.666693317883, 576.8586696259359, 526.12137729991, 582.6737027264745, 583.4836169989505, 608.7930103764742, 652.6377860287491, 8371.41400101987, 8446.934267348515, 8552.486850788973, 8616.827663446697, 8773.298136149093, 518.2795913679988, 537.0655060195232, 592.4874983139639, 605.3492495567194, 622.6077571130159, 3676.632336766302, 3728.738988270795, 3824.8661296854475, 3857.5370955485523, 3887.7084218683713, 60300.39699603036, 60356.79286414247, 60371.09443358353, 60497.0, 60559.39440605626, 52907.735461322445, 54088.18867716988, 54275.72862135743, 54816.45183548193, 54967.17549236765, 48177.0, 48278.5172148274, 49432.50567494279, 49575.256371154464, 50424.99150016979, 7090.929090709027, 7251.0, 7346.5592064476305, 7414.925850741423, 7472.962635186789, 37221.813890930374, 37682.62317376791, 38862.6113738859, 38895.41656875206, 38965.80517097397, 43779.562204378824, 44043.55956440394, 45841.54158458373, 47790.283145752146, 48249.5175048245, 9744.610215591298, 9885.159761420073, 9902.0, 10091.89908100939, 10337.0696637303, 11259.887401125883, 12448.502059917502, 12628.936855315664, 12797.936010319889, 12944.805827912778, 10926.726831829268, 11248.437578121226, 11501.079913606723, 11518.82721759191, 11525.135614829089, 10657.733556661145, 11056.391898445663, 11359.0, 11420.0, 11514.53941842317, 9310.162085412345, 9541.904580954102, 9744.902550974399, 10709.732256693645, 10794.298370605886, 9666.516674166403, 10167.0, 10196.643117490852, 10334.436889162167, 10480.592692841625, 9119.680811171576, 9752.512374381395, 9804.803903921738, 9820.45987470696, 9848.261380396356], "unit": "ms", "name": "yui.html", "value": 6244.834581265728}], "name": "dromaeo_css", "value": 7123.615521831855}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 104.0, 110.0, 112.0, 110.0, 102.0, 109.0, 111.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [148.0, 123.0, 148.0, 164.0, 123.0, 148.0, 158.0, 122.0, 184.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.9}, {"lowerIsBetter": true, "replicates": [159.0, 161.0, 162.0, 159.0, 159.0, 161.0, 160.0, 162.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 148.0, 72.0, 73.0, 71.0, 72.0, 145.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.5}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 143.0, 91.0, 92.0, 92.0, 141.0, 90.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 56.0, 57.0, 57.0, 57.0, 56.0, 56.0, 56.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [76.0, 75.0, 78.0, 78.0, 76.0, 77.0, 79.0, 77.0, 77.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.0}, {"lowerIsBetter": true, "replicates": [115.0, 111.0, 114.0, 112.0, 121.0, 113.0, 113.0, 111.0, 114.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.4}, {"lowerIsBetter": true, "replicates": [145.0, 143.0, 143.0, 144.0, 145.0, 132.0, 148.0, 144.0, 143.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 63.0, 65.0, 63.0, 62.0, 66.0, 64.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.0}], "name": "kraken", "value": 1474.4000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.333998669328], "unit": "score", "name": "Richards", "value": 212.23562399999997}, {"lowerIsBetter": false, "replicates": [165.18004625041294], "unit": "score", "name": "DeltaBlue", "value": 400.27837199999993}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [946.969696969697], "unit": "score", "name": "RayTrace", "value": 781.4283840000004}, {"lowerIsBetter": false, "replicates": [543.773790103317], "unit": "score", "name": "Earley", "value": 1225.6254570000006}, {"lowerIsBetter": false, "replicates": [10510.416666666666], "unit": "score", "name": "Boyer", "value": 63.409760158572894}, {"lowerIsBetter": false, "replicates": [24261.904761904763], "unit": "score", "name": "RegExp", "value": 37.547958783120734}, {"lowerIsBetter": false, "replicates": [525.7623554153523], "unit": "score", "name": "Splay", "value": 154.995882}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23280.839804188276}]} 09:34:16 INFO - RETURN: dromaeo_css: 7123.61 09:34:16 INFO - RETURN: kraken: 1474.40 09:34:16 INFO - RETURN: v8_7: 23280.84 09:34:16 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23280.84"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1474.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7123.61"}}} 09:34:16 INFO - Return code: 0 09:34:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:34:16 INFO - # TBPL SUCCESS # 09:34:16 INFO - Running post-action listener: _resource_record_post_action 09:34:16 INFO - Running post-run listener: _resource_record_post_run 09:34:17 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 4096; Write bytes: 274788352; Read time: 8; Write time: 153460 09:34:17 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 13627392; Read time: 0; Write time: 2888 09:34:17 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 4096; Write bytes: 261160960; Read time: 8; Write time: 150572 09:34:17 INFO - Running post-run listener: _upload_blobber_files 09:34:17 INFO - Blob upload gear active. 09:34:17 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:34:17 INFO - Copying logs to upload dir... 09:34:17 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1268.963964 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-02-25 09:34:17.872060) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:34:17.875586) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009986 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456411770/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:34:17.927093) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:34:17.927439) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7fc86f70bae03e018da0b1d8000001c4-1456420240.471814-627769193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:34:17.977108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:34:17.977482) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:34:17.977767) ========= ========= Total master_lag: 0.27 =========