builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-045 starttime: 1452734845.48 results: success (0) buildid: 20160113151131 builduid: c4b5dc583b7c4664b91d316298e2d3dc revision: 160e194582c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.483077) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.483527) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.483825) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006321 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.510508) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.510791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.526670) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.526954) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004551 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.633729) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.633997) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.634360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.634649) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-13 17:27:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 78.0M=0s 2016-01-13 17:27:25 (78.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160772 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.808061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.808401) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016383 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.857992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:25.858375) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 160e194582c5 --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 160e194582c5 --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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-13 17:27:26,000 Setting DEBUG logging. 2016-01-13 17:27:26,000 attempt 1/10 2016-01-13 17:27:26,000 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/160e194582c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-13 17:27:26,324 unpacking tar archive at: mozilla-beta-160e194582c5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.610751 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:26.481886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:26.482220) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:26.492135) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:26.492413) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 17:27:26.492839) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-13 17:27:26.493159) ========= /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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:27:26 INFO - MultiFileLogger online at 20160113 17:27:26 in /builds/slave/test-pgo 17:27:26 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 17:27:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:27:26 INFO - {'append_to_log': False, 17:27:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:27:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:27:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:27:26 INFO - 'branch': 'Mozilla-Beta', 17:27:26 INFO - 'buildbot_json_path': 'buildprops.json', 17:27:26 INFO - 'config_files': ('talos/linux_config.py',), 17:27:26 INFO - 'default_actions': ('clobber', 17:27:26 INFO - 'read-buildbot-config', 17:27:26 INFO - 'download-and-extract', 17:27:26 INFO - 'populate-webroot', 17:27:26 INFO - 'create-virtualenv', 17:27:26 INFO - 'install', 17:27:26 INFO - 'run-tests'), 17:27:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:27:26 INFO - 'download_minidump_stackwalk': True, 17:27:26 INFO - 'download_symbols': 'ondemand', 17:27:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:27:26 INFO - 'tooltool.py': '/tools/tooltool.py', 17:27:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:27:26 INFO - '/tools/misc-python/virtualenv.py')}, 17:27:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:27:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:27:26 INFO - 'installer_path': 'installer.exe', 17:27:26 INFO - 'log_level': 'info', 17:27:26 INFO - 'log_name': 'talos', 17:27:26 INFO - 'log_to_console': True, 17:27:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:27:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:27:26 INFO - 'opt_config_files': (), 17:27:26 INFO - 'pip_index': False, 17:27:26 INFO - 'sps_profile': False, 17:27:26 INFO - 'sps_profile_interval': 0, 17:27:26 INFO - 'suite': 'dromaeojs', 17:27:26 INFO - 'system_bits': '32', 17:27:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:27:26 INFO - 'title': 'talos-linux64-ix-045', 17:27:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:27:26 INFO - 'use_talos_json': True, 17:27:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:27:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:27:26 INFO - 'work_dir': 'build'} 17:27:26 INFO - ##### 17:27:26 INFO - ##### Running clobber step. 17:27:26 INFO - ##### 17:27:26 INFO - Running pre-action listener: _resource_record_pre_action 17:27:26 INFO - Running main action method: clobber 17:27:26 INFO - rmtree: /builds/slave/test-pgo/build 17:27:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:27:27 INFO - Running post-action listener: _resource_record_post_action 17:27:27 INFO - ##### 17:27:27 INFO - ##### Running read-buildbot-config step. 17:27:27 INFO - ##### 17:27:27 INFO - Running pre-action listener: _resource_record_pre_action 17:27:27 INFO - Running main action method: read_buildbot_config 17:27:27 INFO - Using buildbot properties: 17:27:27 INFO - { 17:27:27 INFO - "properties": { 17:27:27 INFO - "buildnumber": 87, 17:27:27 INFO - "product": "firefox", 17:27:27 INFO - "script_repo_revision": "production", 17:27:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:27:27 INFO - "repository": "", 17:27:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:27:27 INFO - "buildid": "20160113151131", 17:27:27 INFO - "slavename": "talos-linux64-ix-045", 17:27:27 INFO - "pgo_build": "True", 17:27:27 INFO - "basedir": "/builds/slave/test-pgo", 17:27:27 INFO - "project": "", 17:27:27 INFO - "platform": "ubuntu64_hw", 17:27:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:27:27 INFO - "slavebuilddir": "test", 17:27:27 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:27:27 INFO - "branch": "mozilla-beta", 17:27:27 INFO - "repo_path": "releases/mozilla-beta", 17:27:27 INFO - "stage_platform": "linux64-pgo", 17:27:27 INFO - "builduid": "c4b5dc583b7c4664b91d316298e2d3dc", 17:27:27 INFO - "revision": "160e194582c5" 17:27:27 INFO - }, 17:27:27 INFO - "sourcestamp": { 17:27:27 INFO - "repository": "", 17:27:27 INFO - "hasPatch": false, 17:27:27 INFO - "project": "", 17:27:27 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 17:27:27 INFO - "changes": [ 17:27:27 INFO - { 17:27:27 INFO - "category": null, 17:27:27 INFO - "files": [ 17:27:27 INFO - { 17:27:27 INFO - "url": null, 17:27:27 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2" 17:27:27 INFO - } 17:27:27 INFO - ], 17:27:27 INFO - "repository": "", 17:27:27 INFO - "rev": "160e194582c5", 17:27:27 INFO - "who": "sendchange", 17:27:27 INFO - "when": 1452734816, 17:27:27 INFO - "number": 7457314, 17:27:27 INFO - "comments": "Bug 1218473: Add check for presence of NVIDIA Optimus drivers to WindowsNopSpacePatcher_ r=ehsan a=ritu", 17:27:27 INFO - "project": "", 17:27:27 INFO - "at": "Wed 13 Jan 2016 17:26:56", 17:27:27 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 17:27:27 INFO - "revlink": "", 17:27:27 INFO - "properties": [ 17:27:27 INFO - [ 17:27:27 INFO - "buildid", 17:27:27 INFO - "20160113151131", 17:27:27 INFO - "Change" 17:27:27 INFO - ], 17:27:27 INFO - [ 17:27:27 INFO - "builduid", 17:27:27 INFO - "c4b5dc583b7c4664b91d316298e2d3dc", 17:27:27 INFO - "Change" 17:27:27 INFO - ], 17:27:27 INFO - [ 17:27:27 INFO - "pgo_build", 17:27:27 INFO - "True", 17:27:27 INFO - "Change" 17:27:27 INFO - ] 17:27:27 INFO - ], 17:27:27 INFO - "revision": "160e194582c5" 17:27:27 INFO - } 17:27:27 INFO - ], 17:27:27 INFO - "revision": "160e194582c5" 17:27:27 INFO - } 17:27:27 INFO - } 17:27:27 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2. 17:27:27 INFO - Running post-action listener: _resource_record_post_action 17:27:27 INFO - ##### 17:27:27 INFO - ##### Running download-and-extract step. 17:27:27 INFO - ##### 17:27:27 INFO - Running pre-action listener: _resource_record_pre_action 17:27:27 INFO - Running main action method: download_and_extract 17:27:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/test_packages.json 17:27:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:27:27 INFO - Downloaded 1270 bytes. 17:27:27 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:27:27 INFO - Using the following test package requirements: 17:27:27 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:27:27 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:27:27 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'jsshell-linux-x86_64.zip'], 17:27:27 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:27:27 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:27:27 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 17:27:27 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 17:27:27 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:27:27 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 17:27:27 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 17:27:27 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:27:27 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:27:32 INFO - Downloaded 22572184 bytes. 17:27:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 17:27:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:32 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:27:33 INFO - Return code: 0 17:27:33 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:27: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')]} 17:27:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:27:36 INFO - Downloaded 22572184 bytes. 17:27:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 17:27:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 17:27:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:27:37 INFO - Return code: 0 17:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 17:27:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 17:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:27:40 INFO - Downloaded 11131970 bytes. 17:27:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 17:27:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 17:27:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 17:27:40 INFO - Return code: 0 17:27:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:27:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:27:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:27:45 INFO - Downloaded 56629400 bytes. 17:27:45 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:27:45 INFO - mkdir: /builds/slave/test-pgo/properties 17:27:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:27:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:27:45 INFO - Contents: 17:27:45 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2 17:27:45 INFO - Running post-action listener: _resource_record_post_action 17:27:45 INFO - Running post-action listener: set_extra_try_arguments 17:27:45 INFO - ##### 17:27:45 INFO - ##### Running populate-webroot step. 17:27:45 INFO - ##### 17:27:45 INFO - Running pre-action listener: _resource_record_pre_action 17:27:45 INFO - Running main action method: populate_webroot 17:27:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:27:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:27:45 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 17:27:45 INFO - '../fennec_ids.txt'], 17:27:45 INFO - 'tests': ['tcheck2']}, 17:27:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:27:45 INFO - '--tppagecycles', 17:27:45 INFO - '7'], 17:27:45 INFO - 'tests': ['tsvgm']}}, 17:27:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:27:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:27:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:27:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:27:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:27:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'tests': ['damp', 'tps']}, 17:27:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['damp', 'tps']}, 17:27:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:27:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:27:45 INFO - 'other': {'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'other_l64': {'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'other_nol64': {'tests': ['a11yr', 17:27:45 INFO - 'ts_paint', 17:27:45 INFO - 'tpaint', 17:27:45 INFO - 'sessionrestore', 17:27:45 INFO - 'sessionrestore_no_auto_restore']}, 17:27:45 INFO - 'svgr': {'tests': ['tsvgx', 17:27:45 INFO - 'tsvgr_opacity', 17:27:45 INFO - 'tart', 17:27:45 INFO - 'tscrollx', 17:27:45 INFO - 'cart']}, 17:27:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['tsvgx', 17:27:45 INFO - 'tsvgr_opacity', 17:27:45 INFO - 'tart', 17:27:45 INFO - 'tscrollx', 17:27:45 INFO - 'cart']}, 17:27:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'tests': ['tp5o']}, 17:27:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'talos_options': ['--e10s'], 17:27:45 INFO - 'tests': ['tp5o']}, 17:27:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'talos_options': ['--xperf_path', 17:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:45 INFO - 'tests': ['tp5n']}, 17:27:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:27:45 INFO - 'talos_options': ['--e10s', 17:27:45 INFO - '--xperf_path', 17:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:27:45 INFO - 'tests': ['tp5n']}}, 17:27:45 INFO - 'talos.zip': {'path': '', 17:27:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:27:45 INFO - Running post-action listener: _resource_record_post_action 17:27:45 INFO - ##### 17:27:45 INFO - ##### Running create-virtualenv step. 17:27:45 INFO - ##### 17:27:45 INFO - Running pre-action listener: _resource_record_pre_action 17:27:45 INFO - Running main action method: create_virtualenv 17:27:45 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:27:45 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 17:27:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:27:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:27:45 INFO - Using real prefix '/usr' 17:27:45 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:27:46 INFO - Installing distribute.............................................................................................................................................................................................done. 17:27:48 INFO - Installing pip.................done. 17:27:48 INFO - Return code: 0 17:27:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:48 INFO - Using env: {'DISPLAY': ':0', 17:27:48 INFO - 'HOME': '/home/cltbld', 17:27:48 INFO - 'LANG': 'en_US.UTF-8', 17:27:48 INFO - 'LANGUAGE': 'en_US:en', 17:27:48 INFO - 'LOGNAME': 'cltbld', 17:27:48 INFO - 'MAIL': '/var/mail/cltbld', 17:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:48 INFO - 'MOZ_NO_REMOTE': '1', 17:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:48 INFO - 'NO_EM_RESTART': '1', 17:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:48 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:48 INFO - 'SHELL': '/bin/bash', 17:27:48 INFO - 'SHLVL': '1', 17:27:48 INFO - 'TERM': 'linux', 17:27:48 INFO - 'TMOUT': '86400', 17:27:48 INFO - 'USER': 'cltbld', 17:27:48 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:48 INFO - '_': '/tools/buildbot/bin/python'} 17:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:48 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 17:27:48 INFO - Cleaning up... 17:27:48 INFO - Return code: 0 17:27:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:48 INFO - Using env: {'DISPLAY': ':0', 17:27:48 INFO - 'HOME': '/home/cltbld', 17:27:48 INFO - 'LANG': 'en_US.UTF-8', 17:27:48 INFO - 'LANGUAGE': 'en_US:en', 17:27:48 INFO - 'LOGNAME': 'cltbld', 17:27:48 INFO - 'MAIL': '/var/mail/cltbld', 17:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:48 INFO - 'MOZ_NO_REMOTE': '1', 17:27:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:48 INFO - 'NO_EM_RESTART': '1', 17:27:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:48 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:48 INFO - 'SHELL': '/bin/bash', 17:27:48 INFO - 'SHLVL': '1', 17:27:48 INFO - 'TERM': 'linux', 17:27:48 INFO - 'TMOUT': '86400', 17:27:48 INFO - 'USER': 'cltbld', 17:27:48 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:48 INFO - '_': '/tools/buildbot/bin/python'} 17:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:48 INFO - Downloading/unpacking psutil>=0.7.1 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27:50 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 17:27:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:27:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:27:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:50 INFO - Installing collected packages: psutil 17:27:50 INFO - Running setup.py install for psutil 17:27:50 INFO - building 'psutil._psutil_linux' extension 17:27:50 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 17:27:50 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 17:27:51 INFO - building 'psutil._psutil_posix' extension 17:27:51 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 17:27:51 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 17:27:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:27:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:27:51 INFO - Successfully installed psutil 17:27:51 INFO - Cleaning up... 17:27:51 INFO - Return code: 0 17:27:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:27:51 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')]} 17:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:51 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')]} 17:27:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:51 INFO - Using env: {'DISPLAY': ':0', 17:27:51 INFO - 'HOME': '/home/cltbld', 17:27:51 INFO - 'LANG': 'en_US.UTF-8', 17:27:51 INFO - 'LANGUAGE': 'en_US:en', 17:27:51 INFO - 'LOGNAME': 'cltbld', 17:27:51 INFO - 'MAIL': '/var/mail/cltbld', 17:27:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:51 INFO - 'MOZ_NO_REMOTE': '1', 17:27:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:51 INFO - 'NO_EM_RESTART': '1', 17:27:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:51 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:51 INFO - 'SHELL': '/bin/bash', 17:27:51 INFO - 'SHLVL': '1', 17:27:51 INFO - 'TERM': 'linux', 17:27:51 INFO - 'TMOUT': '86400', 17:27:51 INFO - 'USER': 'cltbld', 17:27:51 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:51 INFO - '_': '/tools/buildbot/bin/python'} 17:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:27:51 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 17:27:51 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 17:27:51 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 17:27:51 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 17:27:51 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 17:27:51 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 17:27:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:27:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:27:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:27:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:27:53 INFO - Installing collected packages: mozsystemmonitor 17:27:53 INFO - Running setup.py install for mozsystemmonitor 17:27:53 INFO - Successfully installed mozsystemmonitor 17:27:53 INFO - Cleaning up... 17:27:53 INFO - Return code: 0 17:27:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:53 INFO - Using env: {'DISPLAY': ':0', 17:27:53 INFO - 'HOME': '/home/cltbld', 17:27:53 INFO - 'LANG': 'en_US.UTF-8', 17:27:53 INFO - 'LANGUAGE': 'en_US:en', 17:27:53 INFO - 'LOGNAME': 'cltbld', 17:27:53 INFO - 'MAIL': '/var/mail/cltbld', 17:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:53 INFO - 'MOZ_NO_REMOTE': '1', 17:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:53 INFO - 'NO_EM_RESTART': '1', 17:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:53 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:53 INFO - 'SHELL': '/bin/bash', 17:27:53 INFO - 'SHLVL': '1', 17:27:53 INFO - 'TERM': 'linux', 17:27:53 INFO - 'TMOUT': '86400', 17:27:53 INFO - 'USER': 'cltbld', 17:27:53 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:53 INFO - '_': '/tools/buildbot/bin/python'} 17:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:53 INFO - Downloading/unpacking blobuploader==1.2.4 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27: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 17:27:55 INFO - Downloading blobuploader-1.2.4.tar.gz 17:27:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:27:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:27:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:27:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:27:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 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 17:27:55 INFO - Downloading docopt-0.6.1.tar.gz 17:27:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:27:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 17:27:56 INFO - Running setup.py install for blobuploader 17:27:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:27:56 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:27:56 INFO - Running setup.py install for requests 17:27:56 INFO - Running setup.py install for docopt 17:27:56 INFO - Successfully installed blobuploader requests docopt 17:27:56 INFO - Cleaning up... 17:27:56 INFO - Return code: 0 17:27:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:56 INFO - Using env: {'DISPLAY': ':0', 17:27:56 INFO - 'HOME': '/home/cltbld', 17:27:56 INFO - 'LANG': 'en_US.UTF-8', 17:27:56 INFO - 'LANGUAGE': 'en_US:en', 17:27:56 INFO - 'LOGNAME': 'cltbld', 17:27:56 INFO - 'MAIL': '/var/mail/cltbld', 17:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:56 INFO - 'MOZ_NO_REMOTE': '1', 17:27:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:56 INFO - 'NO_EM_RESTART': '1', 17:27:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:56 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:56 INFO - 'SHELL': '/bin/bash', 17:27:56 INFO - 'SHLVL': '1', 17:27:56 INFO - 'TERM': 'linux', 17:27:56 INFO - 'TMOUT': '86400', 17:27:56 INFO - 'USER': 'cltbld', 17:27:56 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:56 INFO - '_': '/tools/buildbot/bin/python'} 17:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:27:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:27:56 INFO - Running setup.py (path:/tmp/pip-Bor0Jh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:27:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:27:56 INFO - Running setup.py (path:/tmp/pip-3PayGc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:27:57 INFO - Running setup.py (path:/tmp/pip-sxG96W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:27:57 INFO - Running setup.py (path:/tmp/pip-RR_onO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:27:57 INFO - Running setup.py (path:/tmp/pip-BQlfOi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:27:57 INFO - Running setup.py (path:/tmp/pip-zIrMgz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:27:57 INFO - Running setup.py (path:/tmp/pip-Un86pL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:27:57 INFO - Running setup.py (path:/tmp/pip-E1yXGn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:27:57 INFO - Running setup.py (path:/tmp/pip-kzTwte-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:27:57 INFO - Running setup.py (path:/tmp/pip-OA9y1q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:27:57 INFO - Running setup.py (path:/tmp/pip-PRPOzH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:27:57 INFO - Running setup.py (path:/tmp/pip-iwRrHI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:27:57 INFO - Running setup.py (path:/tmp/pip-nyOO9X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:27:57 INFO - Running setup.py (path:/tmp/pip-U8Hivd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:27:57 INFO - Running setup.py (path:/tmp/pip-jRaryl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:27:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:27:57 INFO - Running setup.py (path:/tmp/pip-lml2z4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:27:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:27:58 INFO - Running setup.py (path:/tmp/pip-8WIdva-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:27:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:27:58 INFO - Running setup.py install for manifestparser 17:27:58 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Running setup.py install for mozcrash 17:27:58 INFO - Running setup.py install for mozdebug 17:27:58 INFO - Running setup.py install for mozdevice 17:27:58 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Running setup.py install for mozfile 17:27:58 INFO - Running setup.py install for mozhttpd 17:27:58 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Running setup.py install for mozinfo 17:27:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Running setup.py install for mozInstall 17:27:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:27:58 INFO - Running setup.py install for mozleak 17:27:58 INFO - Running setup.py install for mozlog 17:27:59 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Running setup.py install for moznetwork 17:27:59 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Running setup.py install for mozprocess 17:27:59 INFO - Running setup.py install for mozprofile 17:27:59 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Running setup.py install for mozrunner 17:27:59 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Running setup.py install for mozscreenshot 17:27:59 INFO - Running setup.py install for moztest 17:27:59 INFO - Running setup.py install for mozversion 17:27:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:27:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:27:59 INFO - Cleaning up... 17:27:59 INFO - Return code: 0 17:27:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:27:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:27:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:27:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:27:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:27:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:27:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:27:59 INFO - Using env: {'DISPLAY': ':0', 17:27:59 INFO - 'HOME': '/home/cltbld', 17:27:59 INFO - 'LANG': 'en_US.UTF-8', 17:27:59 INFO - 'LANGUAGE': 'en_US:en', 17:27:59 INFO - 'LOGNAME': 'cltbld', 17:27:59 INFO - 'MAIL': '/var/mail/cltbld', 17:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:27:59 INFO - 'MOZ_NO_REMOTE': '1', 17:27:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:27:59 INFO - 'NO_EM_RESTART': '1', 17:27:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:27:59 INFO - 'PWD': '/builds/slave/test-pgo', 17:27:59 INFO - 'SHELL': '/bin/bash', 17:27:59 INFO - 'SHLVL': '1', 17:27:59 INFO - 'TERM': 'linux', 17:27:59 INFO - 'TMOUT': '86400', 17:27:59 INFO - 'USER': 'cltbld', 17:27:59 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:27:59 INFO - '_': '/tools/buildbot/bin/python'} 17:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:28:00 INFO - Running setup.py (path:/tmp/pip-l1cv5e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:28:00 INFO - Running setup.py (path:/tmp/pip-Kv5Msr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:28:00 INFO - Running setup.py (path:/tmp/pip-akyEB9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:28:00 INFO - Running setup.py (path:/tmp/pip-SQrD85-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:28:00 INFO - Running setup.py (path:/tmp/pip-YAfQVY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:28:00 INFO - Running setup.py (path:/tmp/pip-MGyMaC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:28:00 INFO - Running setup.py (path:/tmp/pip-gazEgO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:28:00 INFO - Running setup.py (path:/tmp/pip-AhbMcU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:28:00 INFO - Running setup.py (path:/tmp/pip-gav94V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:28:00 INFO - Running setup.py (path:/tmp/pip-zU1cgc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:28:00 INFO - Running setup.py (path:/tmp/pip-N8FcGz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:28:00 INFO - Running setup.py (path:/tmp/pip-S5CGhs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:28:00 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)) 17:28:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:28:00 INFO - Running setup.py (path:/tmp/pip-jCrRSp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:28:01 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)) 17:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:28:01 INFO - Running setup.py (path:/tmp/pip-TXDxoz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:28:01 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)) 17:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:28:01 INFO - Running setup.py (path:/tmp/pip-emL2Rs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:28:01 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)) 17:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:28:01 INFO - Running setup.py (path:/tmp/pip-xM0aWg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:28:01 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)) 17:28:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:28:01 INFO - Running setup.py (path:/tmp/pip-CWx0p_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:28:01 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)) 17:28:01 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)) 17:28:01 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)) 17:28:01 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)) 17:28:01 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:28:01 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:28:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:03 INFO - Downloading blessings-1.5.1.tar.gz 17:28:03 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 17:28:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:28:03 INFO - Installing collected packages: blessings 17:28:03 INFO - Running setup.py install for blessings 17:28:03 INFO - Successfully installed blessings 17:28:03 INFO - Cleaning up... 17:28:03 INFO - Return code: 0 17:28:03 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:28:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:28:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:28:03 INFO - Reading from file tmpfile_stdout 17:28:03 INFO - Current package versions: 17:28:03 INFO - argparse == 1.2.1 17:28:03 INFO - blessings == 1.5.1 17:28:03 INFO - blobuploader == 1.2.4 17:28:03 INFO - docopt == 0.6.1 17:28:03 INFO - manifestparser == 1.1 17:28:03 INFO - mozInstall == 1.12 17:28:03 INFO - mozcrash == 0.16 17:28:03 INFO - mozdebug == 0.1 17:28:03 INFO - mozdevice == 0.46 17:28:03 INFO - mozfile == 1.2 17:28:03 INFO - mozhttpd == 0.7 17:28:03 INFO - mozinfo == 0.8 17:28:03 INFO - mozleak == 0.1 17:28:03 INFO - mozlog == 3.0 17:28:03 INFO - moznetwork == 0.27 17:28:03 INFO - mozprocess == 0.22 17:28:03 INFO - mozprofile == 0.27 17:28:03 INFO - mozrunner == 6.11 17:28:03 INFO - mozscreenshot == 0.1 17:28:03 INFO - mozsystemmonitor == 0.0 17:28:03 INFO - moztest == 0.7 17:28:03 INFO - mozversion == 1.4 17:28:03 INFO - psutil == 3.1.1 17:28:03 INFO - requests == 1.2.3 17:28:03 INFO - wsgiref == 0.1.2 17:28:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:28:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:28:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:28:03 INFO - Using env: {'DISPLAY': ':0', 17:28:03 INFO - 'HOME': '/home/cltbld', 17:28:03 INFO - 'LANG': 'en_US.UTF-8', 17:28:03 INFO - 'LANGUAGE': 'en_US:en', 17:28:03 INFO - 'LOGNAME': 'cltbld', 17:28:03 INFO - 'MAIL': '/var/mail/cltbld', 17:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:03 INFO - 'MOZ_NO_REMOTE': '1', 17:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:03 INFO - 'NO_EM_RESTART': '1', 17:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:28:03 INFO - 'PWD': '/builds/slave/test-pgo', 17:28:03 INFO - 'SHELL': '/bin/bash', 17:28:03 INFO - 'SHLVL': '1', 17:28:03 INFO - 'TERM': 'linux', 17:28:03 INFO - 'TMOUT': '86400', 17:28:03 INFO - 'USER': 'cltbld', 17:28:03 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:03 INFO - '_': '/tools/buildbot/bin/python'} 17:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 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)) 17:28:03 INFO - Cleaning up... 17:28:03 INFO - Return code: 0 17:28:03 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:28:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:28:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:28:03 INFO - Using env: {'DISPLAY': ':0', 17:28:03 INFO - 'HOME': '/home/cltbld', 17:28:03 INFO - 'LANG': 'en_US.UTF-8', 17:28:03 INFO - 'LANGUAGE': 'en_US:en', 17:28:03 INFO - 'LOGNAME': 'cltbld', 17:28:03 INFO - 'MAIL': '/var/mail/cltbld', 17:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:03 INFO - 'MOZ_NO_REMOTE': '1', 17:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:03 INFO - 'NO_EM_RESTART': '1', 17:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:28:03 INFO - 'PWD': '/builds/slave/test-pgo', 17:28:03 INFO - 'SHELL': '/bin/bash', 17:28:03 INFO - 'SHLVL': '1', 17:28:03 INFO - 'TERM': 'linux', 17:28:03 INFO - 'TMOUT': '86400', 17:28:03 INFO - 'USER': 'cltbld', 17:28:03 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:03 INFO - '_': '/tools/buildbot/bin/python'} 17:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:03 INFO - Downloading/unpacking jsonschema 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:28:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 17:28:05 INFO - Downloading/unpacking functools32 (from jsonschema) 17:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:05 INFO - Downloading functools32-3.2.3-2.tar.gz 17:28:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:28:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:28:05 INFO - warning: no files found matching '*.txt' 17:28:05 INFO - no previously-included directories found matching 'build' 17:28:05 INFO - no previously-included directories found matching 'dist' 17:28:05 INFO - no previously-included directories found matching '.git*' 17:28:05 INFO - Installing collected packages: jsonschema, functools32 17:28:05 INFO - Running setup.py install for functools32 17:28:06 INFO - warning: no files found matching '*.txt' 17:28:06 INFO - no previously-included directories found matching 'build' 17:28:06 INFO - no previously-included directories found matching 'dist' 17:28:06 INFO - no previously-included directories found matching '.git*' 17:28:06 INFO - Successfully installed jsonschema functools32 17:28:06 INFO - Cleaning up... 17:28:06 INFO - Return code: 0 17:28:06 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1385a98f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd8e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, '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': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', '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 17:28:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 17:28:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 17:28:06 INFO - Using env: {'DISPLAY': ':0', 17:28:06 INFO - 'HOME': '/home/cltbld', 17:28:06 INFO - 'LANG': 'en_US.UTF-8', 17:28:06 INFO - 'LANGUAGE': 'en_US:en', 17:28:06 INFO - 'LOGNAME': 'cltbld', 17:28:06 INFO - 'MAIL': '/var/mail/cltbld', 17:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:06 INFO - 'MOZ_NO_REMOTE': '1', 17:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:06 INFO - 'NO_EM_RESTART': '1', 17:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:28:06 INFO - 'PWD': '/builds/slave/test-pgo', 17:28:06 INFO - 'SHELL': '/bin/bash', 17:28:06 INFO - 'SHLVL': '1', 17:28:06 INFO - 'TERM': 'linux', 17:28:06 INFO - 'TMOUT': '86400', 17:28:06 INFO - 'USER': 'cltbld', 17:28:06 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:06 INFO - '_': '/tools/buildbot/bin/python'} 17:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:06 INFO - Downloading/unpacking flake8 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:28:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:28:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:28:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 17:28:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:28:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 17:28:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 17:28:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 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 17:28:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:28:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:28:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:28:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:28:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:28:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:28:08 INFO - no previously-included directories found matching 'docs/_build' 17:28:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:28:08 INFO - Running setup.py install for pep8 17:28:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:28:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:28:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:28:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:28:08 INFO - no previously-included directories found matching 'docs/_build' 17:28:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:28:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:28:08 INFO - Cleaning up... 17:28:08 INFO - Return code: 0 17:28:08 INFO - Running post-action listener: _resource_record_post_action 17:28:08 INFO - Running post-action listener: _start_resource_monitoring 17:28:08 INFO - Starting resource monitoring. 17:28:08 INFO - ##### 17:28:08 INFO - ##### Running install step. 17:28:08 INFO - ##### 17:28:08 INFO - Running pre-action listener: _resource_record_pre_action 17:28:08 INFO - Running main action method: install 17:28:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:28:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:28:09 INFO - Reading from file tmpfile_stdout 17:28:09 INFO - Detecting whether we're running mozinstall >=1.0... 17:28:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:28:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:28:09 INFO - Reading from file tmpfile_stdout 17:28:09 INFO - Output received: 17:28:09 INFO - Usage: mozinstall [options] installer 17:28:09 INFO - Options: 17:28:09 INFO - -h, --help show this help message and exit 17:28:09 INFO - -d DEST, --destination=DEST 17:28:09 INFO - Directory to install application into. [default: 17:28:09 INFO - "/builds/slave/test-pgo"] 17:28:09 INFO - --app=APP Application being installed. [default: firefox] 17:28:09 INFO - mkdir: /builds/slave/test-pgo/build/application 17:28:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 17:28:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 17:28:23 INFO - Reading from file tmpfile_stdout 17:28:23 INFO - Output received: 17:28:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:28:23 INFO - Running post-action listener: _resource_record_post_action 17:28:23 INFO - ##### 17:28:23 INFO - ##### Running run-tests step. 17:28:23 INFO - ##### 17:28:23 INFO - Running pre-action listener: _resource_record_pre_action 17:28:23 INFO - Running main action method: run_tests 17:28:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:28:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:28:23 INFO - Python 2.7.3 17:28:23 INFO - Return code: 0 17:28:23 INFO - grabbing minidump binary from tooltool 17:28:23 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')]} 17:28:23 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 0xe1d350>, '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 0xe1e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe1e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:28:23 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 17:28:23 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 17:28:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:28:23 INFO - Return code: 0 17:28:23 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:28:23 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:28:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:28:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:28:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:28:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:28:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:28:24 INFO - Return code: 0 17:28:24 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/1452726691/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 17:28:24 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/1452726691/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --webServer localhost 17:28:24 INFO - Using env: {'DISPLAY': ':0', 17:28:24 INFO - 'HOME': '/home/cltbld', 17:28:24 INFO - 'LANG': 'en_US.UTF-8', 17:28:24 INFO - 'LANGUAGE': 'en_US:en', 17:28:24 INFO - 'LOGNAME': 'cltbld', 17:28:24 INFO - 'MAIL': '/var/mail/cltbld', 17:28:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:28:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:24 INFO - 'MOZ_NO_REMOTE': '1', 17:28:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:28:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:28:24 INFO - 'NO_EM_RESTART': '1', 17:28:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:28:24 INFO - 'PWD': '/builds/slave/test-pgo', 17:28:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:28:24 INFO - 'SHELL': '/bin/bash', 17:28:24 INFO - 'SHLVL': '1', 17:28:24 INFO - 'TERM': 'linux', 17:28:24 INFO - 'TMOUT': '86400', 17:28:24 INFO - 'USER': 'cltbld', 17:28:24 INFO - 'XDG_SESSION_COOKIE': '1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965', 17:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:24 INFO - '_': '/tools/buildbot/bin/python'} 17:28:24 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/1452726691/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--webServer', 'localhost'] with output_timeout 3600 17:28:24 INFO - mozversion INFO | application_buildid: 20160113151131 17:28:24 INFO - mozversion INFO | application_changeset: 160e194582c5e1c9088afa20bd6ba83ecc5d121e 17:28:24 INFO - mozversion INFO | application_display_name: Nightly 17:28:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:28:24 INFO - mozversion INFO | application_name: Firefox 17:28:24 INFO - mozversion INFO | application_remotingname: firefox 17:28:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:28:24 INFO - mozversion INFO | application_vendor: Mozilla 17:28:24 INFO - mozversion INFO | application_version: 44.0 17:28:24 INFO - mozversion INFO | platform_buildid: 20160113151131 17:28:24 INFO - mozversion INFO | platform_changeset: 160e194582c5e1c9088afa20bd6ba83ecc5d121e 17:28:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:28:24 INFO - mozversion INFO | platform_version: 44.0 17:28:24 INFO - 2016-01-13 17:28:24,599 DEBUG : using testdate: 1452734904 17:28:24 INFO - 2016-01-13 17:28:24,600 DEBUG : actual date: 1452734904 17:28:24 INFO - 2016-01-13 17:28:24,608 INFO : starting webserver on 'localhost:40242' 17:28:24 INFO - 2016-01-13 17:28:24,609 INFO : Starting test suite talos-linux64-ix-045 17:28:24 INFO - 2016-01-13 17:28:24,609 INFO : Starting test dromaeo_css 17:28:24 INFO - 2016-01-13 17:28:24,609 DEBUG : operating with platform_type : linux_ 17:28:24 INFO - 2016-01-13 17:28:24,610 INFO : Initialising browser for dromaeo_css test... 17:28:24 INFO - 2016-01-13 17:28:24,626 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOczJxX/profile http://localhost:40242/getInfo.html 17:28:27 INFO - 2016-01-13 17:28:27,842 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:28:27 INFO - 2016-01-13 17:28:27,843 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:28:27 INFO - 2016-01-13 17:28:27,843 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:28:27 INFO - 2016-01-13 17:28:27,843 DEBUG : BROWSER_OUTPUT: __metrics 17:28:27 INFO - 2016-01-13 17:28:27,972 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] 17:28:28 INFO - 2016-01-13 17:28:28,992 INFO : Browser initialized. 17:28:28 INFO - 2016-01-13 17:28:28,993 INFO : Running cycle 1/1 for dromaeo_css test... 17:28:28 INFO - 2016-01-13 17:28:28,993 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOczJxX/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:28:40 INFO - 2016-01-13 17:28:40,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 128204800 17:28:40 INFO - 2016-01-13 17:28:40,474 DEBUG : BROWSER_OUTPUT: 17:29:31 INFO - 2016-01-13 17:29:31,579 DEBUG : BROWSER_OUTPUT: 1452734971578 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] 17:31:39 INFO - 2016-01-13 17:31:39,621 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40242/tests/dromaeo/cssquery-ext.html) 17:31:40 INFO - 2016-01-13 17:31:40,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 211451904 17:31:40 INFO - 2016-01-13 17:31:40,590 DEBUG : BROWSER_OUTPUT: 17:34:42 INFO - 2016-01-13 17:34:42,707 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-ext.html (next: http://localhost:40242/tests/dromaeo/cssquery-jquery.html) 17:34:43 INFO - 2016-01-13 17:34:43,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 211795968 17:34:43 INFO - 2016-01-13 17:34:43,261 DEBUG : BROWSER_OUTPUT: 17:37:44 INFO - 2016-01-13 17:37:44,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40242/tests/dromaeo/cssquery-mootools.html) 17:37:45 INFO - 2016-01-13 17:37:45,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 201191424 17:37:45 INFO - 2016-01-13 17:37:45,498 DEBUG : BROWSER_OUTPUT: 17:40:47 INFO - 2016-01-13 17:40:47,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40242/tests/dromaeo/cssquery-prototype.html) 17:40:47 INFO - 2016-01-13 17:40:47,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 194969600 17:40:47 INFO - 2016-01-13 17:40:47,707 DEBUG : BROWSER_OUTPUT: 17:43:50 INFO - 2016-01-13 17:43:50,028 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40242/tests/dromaeo/cssquery-yui.html) 17:43:50 INFO - 2016-01-13 17:43:50,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 196730880 17:43:50 INFO - 2016-01-13 17:43:50,598 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,659 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/dromaeo/cssquery-yui.html (next: http://localhost:40242/tests/dromaeo/cssquery-dojo.html) 17:46:52 INFO - 2016-01-13 17:46:52,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 236990464 17:46:52 INFO - 2016-01-13 17:46:52,904 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,904 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:46:52 INFO - 2016-01-13 17:46:52,905 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:46:52 INFO - 2016-01-13 17:46:52,905 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:46:52 INFO - 2016-01-13 17:46:52,906 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:46:52 INFO - 2016-01-13 17:46:52,907 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3341.3150304187643;3392.2537041850815;3405.6764607362306;3411.522386865839;3435.914102147445;5676.829695109143;5685.459881311274;5686.346070201928;5688.886222275552;5714.685692286923;5689.431056894319;5719;5719.571032172583;5722.37053924068;5724.370319264877;5031.32077169581;5194.194899790538;5203.713795741232;5260.92108618371;5289.761960711778;5215.374155101393;5217.739113044351;5232.215167724834;5240.60695447841;5247.344081989751;5165.586753059765;5272.6836389816735;5299.231611416342;5310.07073762092;5315.7873685052555;5196.0647083524955;5298.337707786527;5301.098813201765;5301.231321458367;5303.23103150045;5276;5278.630495865291;5295.338082739658;5299.49654782795;5332.493413125747;5328.786848526093;5431.864203394946;5440.265564148829;5551.694656793875;5613.607047506676;6107.136625370773;6138.416850399205;6150.907736383958;6156.138140660311;6222.222222222223;6180.381961803829;6184.412480814361;6191.659458729768;6193.5354848386105;6197.349278325801;5587.664740115606;5668;5673.8581535461535;5711.885762284731;5743.971280143573;6250.124982502453;6350.809475715736;6366.5861718989045;6372.6495042773095;6378.107065010902;5909.497692696169;6177.887980163614;6190.876182476325;6238.781642642486;6247.5939063960695;6045.939540604626;6109.175261339751;6232.8130156095385;6257.374262573706;6283.842903927347;6102.786402475892;6145.938540614626;6147.354527774609;6148.538859585459;6149.477294430024;5738.856528586819;5869.8239052828485;6144.754209831557;6145.0782382642965;6154.230721159855;4603.148417542765;4678.976605116953;4704.29435584665;4733.5503127202865;4758.571728544448;4211.810468528924;4225.112726327506;4229.830806767756;4243.915121697549;4254.085371645049;5629.521490673339;5733.254676892061;5828.038373668382;5830.562707796932;5840.415958404211;6091;6174.197354343906;6189.102580125857;6193.442590166816;6200.441960223601;5801.158831969341;5805.158252053428;5808.738606762706;5810.505753198045;5824.825255242349;5240.318758561362;5273.3935597406025;5355.946440535623;5368.8657783554945;5369.087255166631;6308.842278943063;6419.903701444482;6426.646534440651;6428.389303016206;6431.196100487439;6196.690165491617;6212.409821066992;6217.222847144107;6218.751249949952;6220.0047992321015;6166.859131060768;6169.475594574512;6205.47253483459;6219.346968568235;6358.523110766619;6035.5667325310005;6058.757649693963;6059.303180134341;6087.543434242538;6099.786507472217;6136.32500424944;6158.876822463615;6162.753489860355;6164.383561643979;6168.074788781719;5885.94114058871;6010.9699451502465;6032.698365081641;6119.67994891955;6128.203333566607;6016.2479690038745;6030.7286172121485;6038.275406951194;6045.093236014633;6088.726007329592;5856.209411729533;5866.9732796759545;5907.40925907423;5908.527317814651;5908.675022873783;5967.6419414835245;6033.818985430444;6147.354527774698;6147.754089836535;6152.78465253714;5941.851453713692;5943.643381396957;5959.314678811818;5960.731767070405;5973.372795856372 17:46:52 INFO - 2016-01-13 17:46:52,909 DEBUG : BROWSER_OUTPUT: |1;ext.html;605.6124080588421;622.4926684751927;623.940725631065;626.4017862940892;627.7896904536985;43539.78230108849;44455.55544444555;44471;44475.110497790025;44491;47443.28835067468;48245.27632085521;48252.51747482524;48275.51724482754;48329.758351208286;8149.348052155843;8251.711190108348;9181.127792859685;9339.439633622005;10356.37861728295;10484;10570.682879513588;10570.788584228349;10580.78838423231;10592.046715795577;10527.052565269125;10618.78762424751;10628.415437150954;10637.095846853026;10642;10559.683209503728;10644.680659580225;10652.201084918584;10653.627123050734;10655.627053053184;10410.583576656925;10863.29388589747;10867.184961127947;10876.782464350668;10882.564697412094;43796;44149.55850441487;44207;44238.33642495365;44270;3699.056740531147;3813.84744610218;4123.8556650517085;4141.64795992341;4469.396631454777;33152.83423582867;34176.48735268948;34273.657263427296;34285.485717714255;34473.82763086194;3962.980185099085;3978.6021397860272;4063.8780836574656;4097.97951010243;4103.199876024177;322463;322851;324111;324478;324557;61133.38866611277;61673.38326616676;74047;75380.62309688417;75474;70876.64561677261;71131.644341779;71154;71460.64269678618;71519.2848071523;18416.815831841777;18615.255389784255;18634.068296585116;18644.34744783926;18644.813551864307;13931.860681393056;14032.158070515801;14044.297785110704;14178;14225.07537010091;22192.55614887693;22529.211477598205;22614.547709045728;22677.2062977795;22682.206122785625;8026.478278911852;8291.33669306454;8299.336053115736;8308.418410711192;8342;1119.9011617480146;1139.401814047615;1148.787474317254;1157.253571446429;1182.562451892788;30702.84648576743;30734.69265307363;30941.226469338446;31364.686353136633;31459.213519661735;1141.828725691153;1161.7444162284285;1184.4492311075392;1258.0941721960173;1283.2621242785435;14445.422183112558;16442.177891105395;16523.83476165223;16613;16697.83302166987;378091;378774;379996;384829;390449;59230.703846480494;59494.10758838621;59554.70222648859;59563;59625;1411.7105993271355;1413.0754321190643;1473.152937061194;1500.924953752308;1572.6776010917733;28631.570526441705;28828.855855720587;29092.56361154542;30921.536176956914;31485.37029259356;49946.500534994186;50393;50425.49574504355;50453.24320135127;50483.242751358026;285016;286256;286973;287250;287348;36245.81877090598;36281.81859090688;36618.8169059153;50232.246516303014;50498.74750626223;50845;51258.74370628123;51812.22281665703;52190.73904630452;52956;42185.57814421816;43635.78182109069;43976.78011609922;44526.77736611296;44568.55431445644;42186;42686;43151;44361;44510;43026;43108.568914310454;43347.783261084754;43877.56122438863;43919.34120988124;41836.95407614834;43348.56651433445;44786.77606611946;44800.327995079446;44926;31929.36141277208;32299.838500807346;32441.67558324481;35049.64950350464;36951.03583789635 17:46:52 INFO - 2016-01-13 17:46:52,910 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.90545316397487;385.9343911535039;386.6404244053031;386.7698719262039;386.9787161706106;12624.684382890433;14709.705805883876;18022.5494362641;20546.280880169197;20556.38330850075;18827.058647067624;18862.71705924412;19170.808291917077;19228.13473393697;19261.807381926177;5612.41069687683;5728.369879313273;5733.025385684444;5756.251687280647;5763.221965034709;4406.118776244748;4533.909321813562;5724.656520608777;6198.256209254873;6394.872102557946;6168.383161683841;6306.810795676137;6337.87324253517;6356.523260755439;6363.363663633622;6221;6222.81331560054;6236.18929539157;6248.156498872662;6248.625082495042;5574.470425309589;5622.044252477089;5684.346300175492;5685.857853553653;5778.306603207642;22360;22520;22746.54506909869;23325.650115248285;23357.766422335728;2203.6804663323887;2209.171560664751;2239.428945618873;2244.494988627546;2386.9403264918515;11069.833952490719;11224.326540407601;11300.717482062932;11441.713957151054;11478.713032174262;1784.1168621532424;1790.8925464472172;1839.8528117750548;1843.861710371714;1866.7106598477121;60180;62026;63818.68090659563;70509.29490705079;72637.27362726358;7631.275028872248;7781.688732450752;7879.409044321699;7905.841883162305;7965.322947549407;9558;9561;9564.234861211226;9581.233501319877;9581.568829402693;6346.27017892939;6465.967670161714;6472.158619379545;6477.255115661759;6507;6853.931460685429;6857.451403887677;6860.794176174722;6920.5847649141215;6921.515493915478;3646.5441819772527;3700.8149592520267;3702.370596998463;3717.20080182762;3717.851285948586;1220.4263995921901;1258.5280519805074;1272.0650322013312;1272.5609664665676;1309.8100775387516;509.5388673250714;514.7477735909378;540.9810656627;542.7069382533388;555.419586532075;12459.750804983849;12893.48426062947;12959.870401295866;12973.286469244093;12989.025823063308;550.8319962411503;553.8643032457073;563.8562166647561;567.5544697412512;570.2188002436689;7263.346298833025;7301.306375894282;7318.451116166309;7434.442416818761;7517.323440890346;594627;670097;672575;679747;680988;81223;82567.5871620638;83403;85667.14332856591;87545.5622721895;590.3004939147124;594.3937184072222;596.0701305962712;596.9462748352669;597.357840321656;11774.705632359259;12304.26174429537;12358.382080896099;12400.441980110736;12501.249925004166;35999.64000359963;36254.274914502086;36299.27401452009;36611.63388366082;38869.2226155481;45960.770196148806;46661.53338466708;46912.29631555598;47451;48043.279350810466;7220.277972202738;7635.083789945242;7647.502912310904;7651.196624354363;7652.73215437457;13175.538856139989;13280;13285.933570332087;13452.46190152422;13529.661758456117;8756.956215218883;11703.2978021319;11848.111391645489;11849.229800062969;11855.52577896909;11660.06719462458;11779.941100294443;11921.940390297994;12037.819432708342;12079.698007549881;12257.509699612272;12430.564930227398;12452.439640216031;12551.560695375618;12595.74808503843;7671.769846904602;7702.460827742004;7737.497062690907;7742.651580678996;7756.495827771177;11752.823707644222;11848.70378240551;12010.279383237033;12139.39303034827;12382.876171238173 17:46:52 INFO - 2016-01-13 17:46:52,910 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1518.764591488319;1520.4526370506617;1742.6253355528559;1824.1608859924434;1843.1797849956765;7732.690692372305;8056.879146812795;8065;8065.153158918317;8076.91923080769;8198.631061602227;8223.794405139859;8225.6298466569;8240.258376746091;8245;2403.351095204294;2440.5362981033545;2451.1298489036403;2457.0908763757434;2463.8521688698647;2421.7336093029753;2431.9513609727796;2440.9267721968367;2457.299669594166;2458.8032957363457;2160.6542953127505;2179.1936983316195;2397.25685037638;2409.0484258717884;2451.44842410458;2393.5452264069772;2397.1490121007055;2417.613181890889;2417.903283868643;2419.794317483016;2362.102401087576;2396.8801559921935;2400.411899084718;2401.1715957994465;2402.915897943564;7180.24607416219;7287.817804554876;7623.390128789683;7733.072031356217;7774.922250777477;5010.874728131789;5078.339815823919;5080.288759573663;5084.822031228926;5092.0579692756955;3279.8688052477873;3320.4521253993303;3321.7342612590933;3324.0360295514515;3342.331533693271;1131.4682099413262;1157.39236900627;1160.3386069940138;1169.1523645357133;1191.1483289448;11224.66326010221;11285.830712539318;11286;11288.2662626929;11327.207095503318;4613.515580863961;4625.838095666635;4665.486796452387;4675.4155730533685;4690.7654617269;9668.516574171263;9680.322377433513;9702.320837541445;9708.077732615531;9732.172765314886;5121.667091639031;5126.128558145124;5129.256257842667;5132.589392848563;5133.358330208724;4648.209804333272;4652.116097941381;4656.604188644003;4658.697184682985;4664.230401983703;3809.5619003814363;3826.6747326477002;3853.8843834685003;3881.902952426212;3899.2396482685604;1262.1921969939247;1275.1520239040933;1286.3182513267982;1286.3568215892053;1286.9871301287055;1460.2041887171517;1464.5761265106266;1475.1591592792006;1497.0194522587722;1499.8500149985132;7899.486533375312;7902.565358905315;7921.207879212148;7921.564313962787;7939.523628582188;534.7433232048638;536.9033573956725;540.2058973309229;540.9567234621221;549.1022178737712;6081.239845019372;6187.597806142587;6397.52018598607;6405.135306733625;6435.6138631681315;9580.329376943686;9700.175485083846;9776.951115244377;9777.9022209777;9782.902170978341;9422.528873556295;9605.087516686044;9790.118889299998;9795.118439340351;9801.264905132144;7671;7760.146383897767;7771.484560510759;7777.066751989684;7777.883331749915;7643.617819109134;7762.805929851573;7769.572673503011;7783.766487005399;7825.373970082266;8715.651373944971;8743.606537705691;8787.91212087871;8931.19619234272;8942.955285223532;8887.911120888966;9069.138431848964;9073.682421115229;9078;9088.04575519561;7321.890171647536;7334.266573342623;7404.370628496638;7405.8148546286775;7445.553266804009;8103.149169337371;8117.634706438106;8153.510789352657;8160.632771525415;8172.141925097779;7622.237776222333;7641.312281894656;7659.387249019954;7723.729669461766;7744.380449563909;7415.295546923034;7507.774766757006;7511.549307041595;7520.323170914644;7584.393248540214;7657.540547567164;7713.344365728976;7715.112762032438;7716;7718.112417072109;6954.513694455415;6959.338862808025;7029.3673569379;7044;7097.361237488651;7084.716611335489;7145.071140751669;7147.463940204401;7152.24901385347;7155.24869888675 17:46:52 INFO - 2016-01-13 17:46:52,911 DEBUG : BROWSER_OUTPUT: |4;prototype.html;353.1084012867509;357.9767315124518;358.2780696895754;372.04199187093235;379.4516923046198;5917.704114794259;5929.911051334234;5941.108833674945;5944.643321400714;5970.910436343458;5616.213730077792;5663.093904975205;5709.457601527848;5796.043652797283;5857.355690874;4529.456465224178;4541.409616749818;4561.361409402678;4563.133004729108;4576.359309696629;4556.043230921494;4558.042811009692;4563.406757121586;4576.290674945372;4581.518940511249;4534.614557762594;4534.7052441591195;4540.341650460681;4540.614047805941;4542;4442.911141777163;4591.908161836761;4607.493175750665;4616.28447590624;4622.260438329885;4067.3288907330257;4076.4700588923547;4082.408050832613;4089.7750623715683;4096.651784598313;5658.462446067658;5660.858478538028;5673.290838645169;5680.11958146488;5680.914786278209;5361.115415956362;5364.785408583652;5370.382406023279;5373.167159090347;5378.78484860609;6163.476104531121;6279.37206279373;6280.277768056686;6282.089097080898;6287.9056814147825;4512.390827238347;4517.661175411858;4518.864434066983;4522.751248681321;4525.117602067598;6475.805725828232;6480.773172938924;6482.9027564586095;6491.0912472254395;6495.8700825983215;4523.683342166017;4594.816207351735;4610.124076425468;4618.214903466419;4636.536346365404;4663.696859704109;4672.696274742131;4673.275642275453;4675.2052151134385;4675.976620116878;5337.066013447631;5339.946600534023;5341.412444631087;5346.812861549827;5349.946500535023;6323.683815809283;6336.112944187817;6339.175907132136;6341.873162536724;6344.49244060474;1658.991705041467;1699.3882202407124;1702.4552143314268;1718.484454663621;1737.8522825559717;1083.631565267797;1091.7270682329417;1104.8729396119388;1149.1898211760688;1153.4405813180601;6007.309159446632;6032.517398608101;6033.7586496540525;6040.395960404012;6048.848778780566;6439.195100612424;6440.162778838721;6442.871142577123;6444.871102578016;6445.258795238514;6017.338092809788;6023.819285421357;6028.698565071729;6029.758809647565;6032.607880487754;3586.8744593939086;3619.8009109498703;3622.565292164957;3622.69207117398;3623.4202527595457;6715.059891615178;6721.6975236114495;6725.562838415487;6726.56277341981;6735.259121496631;6515.348465153446;6648.401643852077;6652;6658.500612453989;6659.800205993828;6737.393634572912;6761.628110453972;6765.086713293741;6765.661716914135;6777.695003724844;6554.737810487527;6555.868882622225;6568.8357791055605;6569.671516424255;6572.572782769104;6343.524235682435;6478.28738838718;6480.870382592416;6483.286838447672;6490.286068532552;6744.426723728538;6747.460203183636;6748.966255168692;6749.223839258351;6757.526973111835;4590.747508887043;4605.83879564227;4626.843289177706;4629.074185162914;4630.07398520304;6583.63789991555;6587.0448784926875;6588.110605068446;6588.209414870246;6591.242007169045;6288.842778930563;6290.0879372491645;6306.243250809932;6314.684265786784;6386.712597933197;6360.872782544416;6361.14124593174;6364.554481186273;6368.42684158428;6371.904421433775;6024.036154215304;6266.185395898687;6270.561060725706;6276.435120839146;6283.2460104787715;6032.366601506954;6273.404026617464;6374.266959299554;6382.266039405527;6383.489320854228;6212.409821066992;6214.564980451325;6216.316205217513;6217.4715149212825;6229.688515574113 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: |5;yui.html;948.8671585977964;952.4237836109158;954.5513608603956;961.1637875048708;975.3757595139117;15398.769018464733;15459;16266.34934602616;16272.83727162728;16274;15012.024218425822;15103.84896151038;15138.545843624708;15143.545693629181;15169.165695886722;787.4802630264027;788.2629741192004;831.4886344897905;861.1818772166436;923.7182659288915;863.0592654007131;867.5228624256665;867.6616119713317;872.9694460693877;930.0513476254216;829.8381815545971;901.0088902207585;902.9593668284942;904.9592768325408;966.1690945786653;825.0511911301991;903.5301643145593;904.0688091266006;905.7509184974152;965.2663975378692;660.9008648702685;662.2979641579941;662.3343539742576;684.4558575932145;718.9964050179768;15184.164870932094;15272.923635381752;15335.463258785889;15337.233138343037;15387.153706546136;13846.376913038937;14453.71092578153;14461;14465;14489.275536223147;11270.943645281803;11364;11372.943135284271;11498.655040348804;11707.882921170765;3919.627635374635;3934.5475270343704;3937.074787424953;3941.408788681692;3963.128111815396;52697.736511317584;53112.734436327955;54948;55128.448715512735;55131.72434137844;8495.362847786317;10797.568097276178;10977.12183025356;10990.065844403156;11096.722581935515;13064.477420903248;13182.60452186436;13184;13219.537316194079;13223.537176198979;7523;7534.397248220129;7624.580648064299;7771.533707977539;7792.376609871197;5034.0686972910125;5040.319556859777;5056.696598204119;5116.386033675983;5116.846494605168;3008.8044277122353;3036.028470889338;3040.9543856842165;3043.9543406848916;3048.527478240876;467.9836205732851;473.9905201895943;478.71516447713554;479.3504800994672;483.1110756208593;647.8866198415353;764.9513212795505;774.8605251054864;788.6608758234014;790.5217343507211;8533.53065581399;8979.775505612412;9052.094790521027;9071;9074.364794464323;622.9221347331584;680.0717021266036;773.4315278270467;802.330054404567;807.576022588146;4232.238197124486;4235.987905744747;4299.054208074219;4322.891927701832;4357.324614684666;71113.28886711066;71739.28260717326;71913.64043179856;72195.6390218056;72843;61381.69309153426;62047.37952620506;62093;62439.6878015607;63503.6824815873;56768.14847777287;56793.14810277767;57686.134707979414;57704;59237;8867.600957956778;9026.413283136575;9031.864522032307;9157.725268241964;9301.102999523853;42014;43116.13767724565;43447.34828977631;44258.33612495751;44464.33303500385;49201.753991229816;49213.753931230116;55960.72019640038;56830;58387.416125839896;8556.914430855611;8925.73222803317;9036.27709783229;9065.27477801761;9102.362834601514;12321.322327271728;13361.599152025456;13553.59339219825;13724.276268542215;13960.720785584028;11850.111241657083;12121.878781212075;12187.207831490925;12371.072169587136;12404.19372740769;11666.883331166919;11827.349495777815;12084.577039803564;12199.085068619712;12498.125131240726;11506.309621422406;11980.101492387932;12007.579734709243;12062.75874482488;12420.441080151235;10554.577816887238;10809.675709728406;10831.891681083089;10953.945230273797;11011.669649910515;9754.317197796086;9768.974257703123;10004.84992725095;10052.447115408722;10284.948575257076 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,707 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: __startTimestamp1452736012903__endTimestamp 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: Number of tests: 6 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5711.76 Median:5908.68 stddev:640.67 (10.8%) stddev-sans-first:615.06 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: Values: 3341.3 3392.3 3405.7 3411.5 3435.9 5676.8 5685.5 5686.3 5688.9 5714.7 5689.4 5719.0 5719.6 5722.4 5724.4 5031.3 5194.2 5203.7 5260.9 5289.8 5215.4 5217.7 5232.2 5240.6 5247.3 5165.6 5272.7 5299.2 5310.1 5315.8 5196.1 5298.3 5301.1 5301.2 5303.2 5276.0 5278.6 5295.3 5299.5 5332.5 5328.8 5431.9 5440.3 5551.7 5613.6 6107.1 6138.4 6150.9 6156.1 6222.2 6180.4 6184.4 6191.7 6193.5 6197.3 5587.7 5668.0 5673.9 5711.9 5744.0 6250.1 6350.8 6366.6 6372.6 6378.1 5909.5 6177.9 6190.9 6238.8 6247.6 6045.9 6109.2 6232.8 6257.4 6283.8 6102.8 6145.9 6147.4 6148.5 6149.5 5738.9 5869.8 6144.8 6145.1 6154.2 4603.1 4679.0 4704.3 4733.6 4758.6 4211.8 4225.1 4229.8 4243.9 4254.1 5629.5 5733.3 5828.0 5830.6 5840.4 6091.0 6174.2 6189.1 6193.4 6200.4 5801.2 5805.2 5808.7 5810.5 5824.8 5240.3 5273.4 5355.9 5368.9 5369.1 6308.8 6419.9 6426.6 6428.4 6431.2 6196.7 6212.4 6217.2 6218.8 6220.0 6166.9 6169.5 6205.5 6219.3 6358.5 6035.6 6058.8 6059.3 6087.5 6099.8 6136.3 6158.9 6162.8 6164.4 6168.1 5885.9 6011.0 6032.7 6119.7 6128.2 6016.2 6030.7 6038.3 6045.1 6088.7 5856.2 5867.0 5907.4 5908.5 5908.7 5967.6 6033.8 6147.4 6147.8 6152.8 5941.9 5943.6 5959.3 5960.7 5973.4 17:46:52 INFO - 2016-01-13 17:46:52,913 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,914 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53349.78 Median:378432.50 stddev:87034.68 (23.0%) stddev-sans-first:87188.74 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: Values: 605.6 622.5 623.9 626.4 627.8 43539.8 44455.6 44471.0 44475.1 44491.0 47443.3 48245.3 48252.5 48275.5 48329.8 8149.3 8251.7 9181.1 9339.4 10356.4 10484.0 10570.7 10570.8 10580.8 10592.0 10527.1 10618.8 10628.4 10637.1 10642.0 10559.7 10644.7 10652.2 10653.6 10655.6 10410.6 10863.3 10867.2 10876.8 10882.6 43796.0 44149.6 44207.0 44238.3 44270.0 3699.1 3813.8 4123.9 4141.6 4469.4 33152.8 34176.5 34273.7 34285.5 34473.8 3963.0 3978.6 4063.9 4098.0 4103.2 322463.0 322851.0 324111.0 324478.0 324557.0 61133.4 61673.4 74047.0 75380.6 75474.0 70876.6 71131.6 71154.0 71460.6 71519.3 18416.8 18615.3 18634.1 18644.3 18644.8 13931.9 14032.2 14044.3 14178.0 14225.1 22192.6 22529.2 22614.5 22677.2 22682.2 8026.5 8291.3 8299.3 8308.4 8342.0 1119.9 1139.4 1148.8 1157.3 1182.6 30702.8 30734.7 30941.2 31364.7 31459.2 1141.8 1161.7 1184.4 1258.1 1283.3 14445.4 16442.2 16523.8 16613.0 16697.8 378091.0 378774.0 379996.0 384829.0 390449.0 59230.7 59494.1 59554.7 59563.0 59625.0 1411.7 1413.1 1473.2 1500.9 1572.7 28631.6 28828.9 29092.6 30921.5 31485.4 49946.5 50393.0 50425.5 50453.2 50483.2 285016.0 286256.0 286973.0 287250.0 287348.0 36245.8 36281.8 36618.8 50232.2 50498.7 50845.0 51258.7 51812.2 52190.7 52956.0 42185.6 43635.8 43976.8 44526.8 44568.6 42186.0 42686.0 43151.0 44361.0 44510.0 43026.0 43108.6 43347.8 43877.6 43919.3 41837.0 43348.6 44786.8 44800.3 44926.0 31929.4 32299.8 32441.7 35049.6 36951.0 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31772.32 Median:24276.41 stddev:108030.70 (445.0%) stddev-sans-first:108308.04 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: Values: 385.9 385.9 386.6 386.8 387.0 12624.7 14709.7 18022.5 20546.3 20556.4 18827.1 18862.7 19170.8 19228.1 19261.8 5612.4 5728.4 5733.0 5756.3 5763.2 4406.1 4533.9 5724.7 6198.3 6394.9 6168.4 6306.8 6337.9 6356.5 6363.4 6221.0 6222.8 6236.2 6248.2 6248.6 5574.5 5622.0 5684.3 5685.9 5778.3 22360.0 22520.0 22746.5 23325.7 23357.8 2203.7 2209.2 2239.4 2244.5 2386.9 11069.8 11224.3 11300.7 11441.7 11478.7 1784.1 1790.9 1839.9 1843.9 1866.7 60180.0 62026.0 63818.7 70509.3 72637.3 7631.3 7781.7 7879.4 7905.8 7965.3 9558.0 9561.0 9564.2 9581.2 9581.6 6346.3 6466.0 6472.2 6477.3 6507.0 6853.9 6857.5 6860.8 6920.6 6921.5 3646.5 3700.8 3702.4 3717.2 3717.9 1220.4 1258.5 1272.1 1272.6 1309.8 509.5 514.7 541.0 542.7 555.4 12459.8 12893.5 12959.9 12973.3 12989.0 550.8 553.9 563.9 567.6 570.2 7263.3 7301.3 7318.5 7434.4 7517.3 594627.0 670097.0 672575.0 679747.0 680988.0 81223.0 82567.6 83403.0 85667.1 87545.6 590.3 594.4 596.1 596.9 597.4 11774.7 12304.3 12358.4 12400.4 12501.2 35999.6 36254.3 36299.3 36611.6 38869.2 45960.8 46661.5 46912.3 47451.0 48043.3 7220.3 7635.1 7647.5 7651.2 7652.7 13175.5 13280.0 13285.9 13452.5 13529.7 8757.0 11703.3 11848.1 11849.2 11855.5 11660.1 11779.9 11921.9 12037.8 12079.7 12257.5 12430.6 12452.4 12551.6 12595.7 7671.8 7702.5 7737.5 7742.7 7756.5 11752.8 11848.7 12010.3 12139.4 12382.9 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,915 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5809.14 Median:6956.93 stddev:2987.08 (42.9%) stddev-sans-first:2978.05 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: Values: 1518.8 1520.5 1742.6 1824.2 1843.2 7732.7 8056.9 8065.0 8065.2 8076.9 8198.6 8223.8 8225.6 8240.3 8245.0 2403.4 2440.5 2451.1 2457.1 2463.9 2421.7 2432.0 2440.9 2457.3 2458.8 2160.7 2179.2 2397.3 2409.0 2451.4 2393.5 2397.1 2417.6 2417.9 2419.8 2362.1 2396.9 2400.4 2401.2 2402.9 7180.2 7287.8 7623.4 7733.1 7774.9 5010.9 5078.3 5080.3 5084.8 5092.1 3279.9 3320.5 3321.7 3324.0 3342.3 1131.5 1157.4 1160.3 1169.2 1191.1 11224.7 11285.8 11286.0 11288.3 11327.2 4613.5 4625.8 4665.5 4675.4 4690.8 9668.5 9680.3 9702.3 9708.1 9732.2 5121.7 5126.1 5129.3 5132.6 5133.4 4648.2 4652.1 4656.6 4658.7 4664.2 3809.6 3826.7 3853.9 3881.9 3899.2 1262.2 1275.2 1286.3 1286.4 1287.0 1460.2 1464.6 1475.2 1497.0 1499.9 7899.5 7902.6 7921.2 7921.6 7939.5 534.7 536.9 540.2 541.0 549.1 6081.2 6187.6 6397.5 6405.1 6435.6 9580.3 9700.2 9777.0 9777.9 9782.9 9422.5 9605.1 9790.1 9795.1 9801.3 7671.0 7760.1 7771.5 7777.1 7777.9 7643.6 7762.8 7769.6 7783.8 7825.4 8715.7 8743.6 8787.9 8931.2 8943.0 8887.9 9069.1 9073.7 9078.0 9088.0 7321.9 7334.3 7404.4 7405.8 7445.6 8103.1 8117.6 8153.5 8160.6 8172.1 7622.2 7641.3 7659.4 7723.7 7744.4 7415.3 7507.8 7511.5 7520.3 7584.4 7657.5 7713.3 7715.1 7716.0 7718.1 6954.5 6959.3 7029.4 7044.0 7097.4 7084.7 7145.1 7147.5 7152.2 7155.2 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5306.71 Median:6012.32 stddev:1561.66 (26.0%) stddev-sans-first:1521.13 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: Values: 353.1 358.0 358.3 372.0 379.5 5917.7 5929.9 5941.1 5944.6 5970.9 5616.2 5663.1 5709.5 5796.0 5857.4 4529.5 4541.4 4561.4 4563.1 4576.4 4556.0 4558.0 4563.4 4576.3 4581.5 4534.6 4534.7 4540.3 4540.6 4542.0 4442.9 4591.9 4607.5 4616.3 4622.3 4067.3 4076.5 4082.4 4089.8 4096.7 5658.5 5660.9 5673.3 5680.1 5680.9 5361.1 5364.8 5370.4 5373.2 5378.8 6163.5 6279.4 6280.3 6282.1 6287.9 4512.4 4517.7 4518.9 4522.8 4525.1 6475.8 6480.8 6482.9 6491.1 6495.9 4523.7 4594.8 4610.1 4618.2 4636.5 4663.7 4672.7 4673.3 4675.2 4676.0 5337.1 5339.9 5341.4 5346.8 5349.9 6323.7 6336.1 6339.2 6341.9 6344.5 1659.0 1699.4 1702.5 1718.5 1737.9 1083.6 1091.7 1104.9 1149.2 1153.4 6007.3 6032.5 6033.8 6040.4 6048.8 6439.2 6440.2 6442.9 6444.9 6445.3 6017.3 6023.8 6028.7 6029.8 6032.6 3586.9 3619.8 3622.6 3622.7 3623.4 6715.1 6721.7 6725.6 6726.6 6735.3 6515.3 6648.4 6652.0 6658.5 6659.8 6737.4 6761.6 6765.1 6765.7 6777.7 6554.7 6555.9 6568.8 6569.7 6572.6 6343.5 6478.3 6480.9 6483.3 6490.3 6744.4 6747.5 6749.0 6749.2 6757.5 4590.7 4605.8 4626.8 4629.1 4630.1 6583.6 6587.0 6588.1 6588.2 6591.2 6288.8 6290.1 6306.2 6314.7 6386.7 6360.9 6361.1 6364.6 6368.4 6371.9 6024.0 6266.2 6270.6 6276.4 6283.2 6032.4 6273.4 6374.3 6382.3 6383.5 6212.4 6214.6 6216.3 6217.5 6229.7 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: 17:46:52 INFO - 2016-01-13 17:46:52,917 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15770.87 Median:5048.51 stddev:19581.83 (387.9%) stddev-sans-first:19605.13 17:46:52 INFO - 2016-01-13 17:46:52,918 DEBUG : BROWSER_OUTPUT: Values: 948.9 952.4 954.6 961.2 975.4 15398.8 15459.0 16266.3 16272.8 16274.0 15012.0 15103.8 15138.5 15143.5 15169.2 787.5 788.3 831.5 861.2 923.7 863.1 867.5 867.7 873.0 930.1 829.8 901.0 903.0 905.0 966.2 825.1 903.5 904.1 905.8 965.3 660.9 662.3 662.3 684.5 719.0 15184.2 15272.9 15335.5 15337.2 15387.2 13846.4 14453.7 14461.0 14465.0 14489.3 11270.9 11364.0 11372.9 11498.7 11707.9 3919.6 3934.5 3937.1 3941.4 3963.1 52697.7 53112.7 54948.0 55128.4 55131.7 8495.4 10797.6 10977.1 10990.1 11096.7 13064.5 13182.6 13184.0 13219.5 13223.5 7523.0 7534.4 7624.6 7771.5 7792.4 5034.1 5040.3 5056.7 5116.4 5116.8 3008.8 3036.0 3041.0 3044.0 3048.5 468.0 474.0 478.7 479.4 483.1 647.9 765.0 774.9 788.7 790.5 8533.5 8979.8 9052.1 9071.0 9074.4 622.9 680.1 773.4 802.3 807.6 4232.2 4236.0 4299.1 4322.9 4357.3 71113.3 71739.3 71913.6 72195.6 72843.0 61381.7 62047.4 62093.0 62439.7 63503.7 56768.1 56793.1 57686.1 57704.0 59237.0 8867.6 9026.4 9031.9 9157.7 9301.1 42014.0 43116.1 43447.3 44258.3 44464.3 49201.8 49213.8 55960.7 56830.0 58387.4 8556.9 8925.7 9036.3 9065.3 9102.4 12321.3 13361.6 13553.6 13724.3 13960.7 11850.1 12121.9 12187.2 12371.1 12404.2 11666.9 11827.3 12084.6 12199.1 12498.1 11506.3 11980.1 12007.6 12062.8 12420.4 10554.6 10809.7 10831.9 10953.9 11011.7 9754.3 9769.0 10004.8 10052.4 10284.9 17:46:52 INFO - 2016-01-13 17:46:52,918 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:46:52 INFO - 2016-01-13 17:46:52,919 DEBUG : BROWSER_OUTPUT: 17:46:53 INFO - 2016-01-13 17:46:53,480 INFO : Browser exited with error code: 0 17:46:53 INFO - 2016-01-13 17:46:53,501 INFO : Completed test dromaeo_css (00:18:28) 17:46:53 INFO - 2016-01-13 17:46:53,501 INFO : Starting test kraken 17:46:53 INFO - 2016-01-13 17:46:53,501 DEBUG : operating with platform_type : linux_ 17:46:53 INFO - 2016-01-13 17:46:53,501 INFO : Initialising browser for kraken test... 17:46:53 INFO - 2016-01-13 17:46:53,507 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6sQSrO/profile http://localhost:40242/getInfo.html 17:46:56 INFO - 2016-01-13 17:46:56,508 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:46:56 INFO - 2016-01-13 17:46:56,508 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:46:56 INFO - 2016-01-13 17:46:56,508 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:46:56 INFO - 2016-01-13 17:46:56,508 DEBUG : BROWSER_OUTPUT: __metrics 17:46:56 INFO - 2016-01-13 17:46:56,629 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] 17:46:57 INFO - 2016-01-13 17:46:57,664 INFO : Browser initialized. 17:46:57 INFO - 2016-01-13 17:46:57,665 INFO : Running cycle 1/1 for kraken test... 17:46:57 INFO - 2016-01-13 17:46:57,665 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6sQSrO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:47:09 INFO - 2016-01-13 17:47:09,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 129040384 17:47:09 INFO - 2016-01-13 17:47:09,285 DEBUG : BROWSER_OUTPUT: 17:47:11 INFO - 2016-01-13 17:47:11,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40242/tests/kraken/driver.html?testName=audio-beat-detection) 17:47:12 INFO - 2016-01-13 17:47:12,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 162521088 17:47:12 INFO - 2016-01-13 17:47:12,259 DEBUG : BROWSER_OUTPUT: 17:47:14 INFO - 2016-01-13 17:47:14,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40242/tests/kraken/driver.html?testName=audio-dft) 17:47:15 INFO - 2016-01-13 17:47:15,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 159383552 17:47:15 INFO - 2016-01-13 17:47:15,373 DEBUG : BROWSER_OUTPUT: 17:47:18 INFO - 2016-01-13 17:47:18,134 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40242/tests/kraken/driver.html?testName=audio-fft) 17:47:18 INFO - 2016-01-13 17:47:18,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 157044736 17:47:18 INFO - 2016-01-13 17:47:18,701 DEBUG : BROWSER_OUTPUT: 17:47:20 INFO - 2016-01-13 17:47:20,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40242/tests/kraken/driver.html?testName=audio-oscillator) 17:47:21 INFO - 2016-01-13 17:47:21,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 156712960 17:47:21 INFO - 2016-01-13 17:47:21,186 DEBUG : BROWSER_OUTPUT: 17:47:23 INFO - 2016-01-13 17:47:23,311 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:47:23 INFO - 2016-01-13 17:47:23,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 155189248 17:47:23 INFO - 2016-01-13 17:47:23,878 DEBUG : BROWSER_OUTPUT: 17:47:26 INFO - 2016-01-13 17:47:26,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40242/tests/kraken/driver.html?testName=imaging-darkroom) 17:47:27 INFO - 2016-01-13 17:47:27,303 DEBUG : BROWSER_OUTPUT: RSS: Main: 156098560 17:47:27 INFO - 2016-01-13 17:47:27,303 DEBUG : BROWSER_OUTPUT: 17:47:30 INFO - 2016-01-13 17:47:30,628 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40242/tests/kraken/driver.html?testName=imaging-desaturate) 17:47:31 INFO - 2016-01-13 17:47:31,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 155095040 17:47:31 INFO - 2016-01-13 17:47:31,195 DEBUG : BROWSER_OUTPUT: 17:47:33 INFO - 2016-01-13 17:47:33,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40242/tests/kraken/driver.html?testName=json-parse-financial) 17:47:34 INFO - 2016-01-13 17:47:34,463 DEBUG : BROWSER_OUTPUT: RSS: Main: 155877376 17:47:34 INFO - 2016-01-13 17:47:34,463 DEBUG : BROWSER_OUTPUT: 17:47:36 INFO - 2016-01-13 17:47:36,011 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:47:36 INFO - 2016-01-13 17:47:36,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 159084544 17:47:36 INFO - 2016-01-13 17:47:36,561 DEBUG : BROWSER_OUTPUT: 17:47:38 INFO - 2016-01-13 17:47:38,293 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:47:38 INFO - 2016-01-13 17:47:38,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 157999104 17:47:38 INFO - 2016-01-13 17:47:38,885 DEBUG : BROWSER_OUTPUT: 17:47:40 INFO - 2016-01-13 17:47:40,823 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:47:41 INFO - 2016-01-13 17:47:41,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 163078144 17:47:41 INFO - 2016-01-13 17:47:41,390 DEBUG : BROWSER_OUTPUT: 17:47:43 INFO - 2016-01-13 17:47:43,672 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:47:44 INFO - 2016-01-13 17:47:44,247 DEBUG : BROWSER_OUTPUT: RSS: Main: 168730624 17:47:44 INFO - 2016-01-13 17:47:44,247 DEBUG : BROWSER_OUTPUT: 17:47:46 INFO - 2016-01-13 17:47:46,682 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:47:47 INFO - 2016-01-13 17:47:47,245 DEBUG : BROWSER_OUTPUT: RSS: Main: 168275968 17:47:47 INFO - 2016-01-13 17:47:47,245 DEBUG : BROWSER_OUTPUT: 17:47:48 INFO - 2016-01-13 17:47:48,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40242/tests/kraken/driver.html?testName=ai-astar) 17:47:49 INFO - 2016-01-13 17:47:49,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 227061760 17:47:49 INFO - 2016-01-13 17:47:49,108 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,109 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:47:49 INFO - 2016-01-13 17:47:49,110 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:47:49 INFO - 2016-01-13 17:47:49,110 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;104;111;112;109;98;108;108;104 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;127;145;166;127;146;151;122;167;121 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;160;159;159;159;158;161;158;162 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;73;141;71;71;72;70;147;71 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;90;144;88;90;90;130;90;90;133 17:47:49 INFO - 2016-01-13 17:47:49,111 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;109;109;110;109;109;110 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;144;145;145;145;145;144;145;145;145 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;94;95;95;95;96;96;95;95;96 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;58;58;58;59;60;57;55;57 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;61;61;62;62;63;62;63;64;64;63 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;77;79;79;80;79;78;79;78;79 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;114;113;108;115;112;115;108;112;114 17:47:49 INFO - 2016-01-13 17:47:49,112 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;146;139;137;141;135;131;137;128;142;139 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;63;60;62;61;62;61;61;62;61 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: __startTimestamp1452736069109__endTimestamp 17:47:49 INFO - 2016-01-13 17:47:49,113 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.20 Median:109.50 stddev:4.16 (3.8%) stddev-sans-first:4.40 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 104.0 111.0 112.0 109.0 98.0 108.0 108.0 104.0 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.40 Median:148.50 stddev:17.31 (11.7%) stddev-sans-first:18.01 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: Values: 152.0 127.0 145.0 166.0 127.0 146.0 151.0 122.0 167.0 121.0 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.60 Median:160.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 160.0 159.0 159.0 159.0 158.0 161.0 158.0 162.0 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,117 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.80 Median:71.00 stddev:30.72 (43.3%) stddev-sans-first:32.05 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 73.0 141.0 71.0 71.0 72.0 70.0 147.0 71.0 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.40 Median:90.00 stddev:22.54 (25.0%) stddev-sans-first:23.30 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 144.0 88.0 90.0 90.0 130.0 90.0 90.0 133.0 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.80 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: Values: 145.0 144.0 145.0 145.0 145.0 145.0 144.0 145.0 145.0 145.0 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,118 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.58 (2.7%) stddev-sans-first:1.41 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 58.0 58.0 58.0 59.0 60.0 57.0 55.0 57.0 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:1.08 (1.7%) stddev-sans-first:1.00 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: Values: 61.0 61.0 62.0 62.0 63.0 62.0 63.0 64.0 64.0 63.0 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: Values: 79.0 77.0 79.0 79.0 80.0 79.0 78.0 79.0 78.0 79.0 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.40 Median:113.50 stddev:2.55 (2.2%) stddev-sans-first:2.69 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: Values: 113.0 114.0 113.0 108.0 115.0 112.0 115.0 108.0 112.0 114.0 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.50 Median:139.00 stddev:5.25 (3.8%) stddev-sans-first:4.59 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: Values: 146.0 139.0 137.0 141.0 135.0 131.0 137.0 128.0 142.0 139.0 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,119 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:0.88 17:47:49 INFO - 2016-01-13 17:47:49,120 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 60.0 62.0 61.0 62.0 61.0 61.0 62.0 61.0 17:47:49 INFO - 2016-01-13 17:47:49,120 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:47:49 INFO - 2016-01-13 17:47:49,120 DEBUG : BROWSER_OUTPUT: 17:47:49 INFO - 2016-01-13 17:47:49,736 INFO : Browser exited with error code: 0 17:47:49 INFO - 2016-01-13 17:47:49,791 INFO : Completed test kraken (00:00:56) 17:47:49 INFO - 2016-01-13 17:47:49,791 INFO : Starting test v8_7 17:47:49 INFO - 2016-01-13 17:47:49,791 DEBUG : operating with platform_type : linux_ 17:47:49 INFO - 2016-01-13 17:47:49,792 INFO : Initialising browser for v8_7 test... 17:47:49 INFO - 2016-01-13 17:47:49,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp26_9GC/profile http://localhost:40242/getInfo.html 17:47:52 INFO - 2016-01-13 17:47:52,894 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:47:52 INFO - 2016-01-13 17:47:52,894 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:47:52 INFO - 2016-01-13 17:47:52,894 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:47:52 INFO - 2016-01-13 17:47:52,894 DEBUG : BROWSER_OUTPUT: __metrics 17:47:53 INFO - 2016-01-13 17:47:53,016 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] 17:47:54 INFO - 2016-01-13 17:47:54,056 INFO : Browser initialized. 17:47:54 INFO - 2016-01-13 17:47:54,057 INFO : Running cycle 1/1 for v8_7 test... 17:47:54 INFO - 2016-01-13 17:47:54,057 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp26_9GC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:48:05 INFO - 2016-01-13 17:48:05,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 128819200 17:48:05 INFO - 2016-01-13 17:48:05,707 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,058 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40242/tests/v8_7/run.html (next: http://localhost:40242/tests/v8_7/run.html) 17:48:27 INFO - 2016-01-13 17:48:27,309 DEBUG : BROWSER_OUTPUT: RSS: Main: 421470208 17:48:27 INFO - 2016-01-13 17:48:27,309 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,310 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: |0;Richards;164.63615409944023 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.23287671232876 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.01785714285717 17:48:27 INFO - 2016-01-13 17:48:27,311 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5015 17:48:27 INFO - 2016-01-13 17:48:27,312 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.073793755913 17:48:27 INFO - 2016-01-13 17:48:27,312 DEBUG : BROWSER_OUTPUT: |5;Earley;551.2679162072767 17:48:27 INFO - 2016-01-13 17:48:27,312 DEBUG : BROWSER_OUTPUT: |6;Boyer;10531.25 17:48:27 INFO - 2016-01-13 17:48:27,312 DEBUG : BROWSER_OUTPUT: |7;RegExp;23952.380952380954 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: |8;Splay;531.9148936170212 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: __startTimestamp1452736107310__endTimestamp 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,313 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:48:27 INFO - 2016-01-13 17:48:27,314 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:48:27 INFO - 2016-01-13 17:48:27,314 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,314 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,314 DEBUG : BROWSER_OUTPUT: Values: 164.6 17:48:27 INFO - 2016-01-13 17:48:27,315 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.23 Median:171.23 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: Values: 171.2 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: Values: 279.0 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5015.00 Median:5015.00 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,316 DEBUG : BROWSER_OUTPUT: Values: 5015.0 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.07 Median:946.07 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: Values: 946.1 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:551.27 Median:551.27 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: Values: 551.3 17:48:27 INFO - 2016-01-13 17:48:27,317 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10531.25 Median:10531.25 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: Values: 10531.3 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23952.38 Median:23952.38 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: Values: 23952.4 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,318 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:531.91 Median:531.91 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: Values: 531.9 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: Values: 4826.9 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:48:27 INFO - 2016-01-13 17:48:27,319 DEBUG : BROWSER_OUTPUT: 17:48:27 INFO - 2016-01-13 17:48:27,960 INFO : Browser exited with error code: 0 17:48:27 INFO - 2016-01-13 17:48:27,974 INFO : Completed test v8_7 (00:00:38) 17:48:28 INFO - 2016-01-13 17:48:28,383 INFO : Completed test suite (00:20:03) 17:48:28 INFO - 2016-01-13 17:48:28,383 DEBUG : Working with test: dromaeo_css 17:48:28 INFO - 2016-01-13 17:48:28,383 DEBUG : Generating results file: dromaeo_css 17:48:28 INFO - 2016-01-13 17:48:28,384 DEBUG : Working with test: kraken 17:48:28 INFO - 2016-01-13 17:48:28,384 DEBUG : Generating results file: kraken 17:48:28 INFO - 2016-01-13 17:48:28,384 INFO : javascript benchmark 17:48:28 INFO - 2016-01-13 17:48:28,385 DEBUG : Working with test: v8_7 17:48:28 INFO - 2016-01-13 17:48:28,385 DEBUG : Generating results file: v8_7 17:48:28 INFO - 2016-01-13 17:48:28,385 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:48:28 INFO - 2016-01-13 17:48:28,436 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 17:48:28 INFO - 2016-01-13 17:48:28,437 DEBUG : process_Request line: dromaeo_css 7291.46 graph.html#tests=[[72,53,35]] 17:48:28 INFO - 2016-01-13 17:48:28,437 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:48:28 INFO - 2016-01-13 17:48:28,470 DEBUG : process_Request line: kraken 1457.80 graph.html#tests=[[232,53,35]] 17:48:28 INFO - 2016-01-13 17:48:28,470 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:48:28 INFO - 2016-01-13 17:48:28,501 DEBUG : process_Request line: v8_7 23275.43 graph.html#tests=[[230,53,35]] 17:48:28 INFO - 2016-01-13 17:48:28,502 INFO : javascript benchmark 17:48:28 INFO - 2016-01-13 17:48:28,506 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3341.3150304187643, 3392.2537041850815, 3405.6764607362306, 3411.522386865839, 3435.914102147445, 5676.829695109143, 5685.459881311274, 5686.346070201928, 5688.886222275552, 5714.685692286923, 5689.431056894319, 5719.0, 5719.571032172583, 5722.37053924068, 5724.370319264877, 5031.32077169581, 5194.194899790538, 5203.713795741232, 5260.92108618371, 5289.761960711778, 5215.374155101393, 5217.739113044351, 5232.215167724834, 5240.60695447841, 5247.344081989751, 5165.586753059765, 5272.6836389816735, 5299.231611416342, 5310.07073762092, 5315.7873685052555, 5196.0647083524955, 5298.337707786527, 5301.098813201765, 5301.231321458367, 5303.23103150045, 5276.0, 5278.630495865291, 5295.338082739658, 5299.49654782795, 5332.493413125747, 5328.786848526093, 5431.864203394946, 5440.265564148829, 5551.694656793875, 5613.607047506676, 6107.136625370773, 6138.416850399205, 6150.907736383958, 6156.138140660311, 6222.222222222223, 6180.381961803829, 6184.412480814361, 6191.659458729768, 6193.5354848386105, 6197.349278325801, 5587.664740115606, 5668.0, 5673.8581535461535, 5711.885762284731, 5743.971280143573, 6250.124982502453, 6350.809475715736, 6366.5861718989045, 6372.6495042773095, 6378.107065010902, 5909.497692696169, 6177.887980163614, 6190.876182476325, 6238.781642642486, 6247.5939063960695, 6045.939540604626, 6109.175261339751, 6232.8130156095385, 6257.374262573706, 6283.842903927347, 6102.786402475892, 6145.938540614626, 6147.354527774609, 6148.538859585459, 6149.477294430024, 5738.856528586819, 5869.8239052828485, 6144.754209831557, 6145.0782382642965, 6154.230721159855, 4603.148417542765, 4678.976605116953, 4704.29435584665, 4733.5503127202865, 4758.571728544448, 4211.810468528924, 4225.112726327506, 4229.830806767756, 4243.915121697549, 4254.085371645049, 5629.521490673339, 5733.254676892061, 5828.038373668382, 5830.562707796932, 5840.415958404211, 6091.0, 6174.197354343906, 6189.102580125857, 6193.442590166816, 6200.441960223601, 5801.158831969341, 5805.158252053428, 5808.738606762706, 5810.505753198045, 5824.825255242349, 5240.318758561362, 5273.3935597406025, 5355.946440535623, 5368.8657783554945, 5369.087255166631, 6308.842278943063, 6419.903701444482, 6426.646534440651, 6428.389303016206, 6431.196100487439, 6196.690165491617, 6212.409821066992, 6217.222847144107, 6218.751249949952, 6220.0047992321015, 6166.859131060768, 6169.475594574512, 6205.47253483459, 6219.346968568235, 6358.523110766619, 6035.5667325310005, 6058.757649693963, 6059.303180134341, 6087.543434242538, 6099.786507472217, 6136.32500424944, 6158.876822463615, 6162.753489860355, 6164.383561643979, 6168.074788781719, 5885.94114058871, 6010.9699451502465, 6032.698365081641, 6119.67994891955, 6128.203333566607, 6016.2479690038745, 6030.7286172121485, 6038.275406951194, 6045.093236014633, 6088.726007329592, 5856.209411729533, 5866.9732796759545, 5907.40925907423, 5908.527317814651, 5908.675022873783, 5967.6419414835245, 6033.818985430444, 6147.354527774698, 6147.754089836535, 6152.78465253714, 5941.851453713692, 5943.643381396957, 5959.314678811818, 5960.731767070405, 5973.372795856372], "unit": "ms", "name": "dojo.html", "value": 5669.643114975441}, {"lowerIsBetter": false, "replicates": [605.6124080588421, 622.4926684751927, 623.940725631065, 626.4017862940892, 627.7896904536985, 43539.78230108849, 44455.55544444555, 44471.0, 44475.110497790025, 44491.0, 47443.28835067468, 48245.27632085521, 48252.51747482524, 48275.51724482754, 48329.758351208286, 8149.348052155843, 8251.711190108348, 9181.127792859685, 9339.439633622005, 10356.37861728295, 10484.0, 10570.682879513588, 10570.788584228349, 10580.78838423231, 10592.046715795577, 10527.052565269125, 10618.78762424751, 10628.415437150954, 10637.095846853026, 10642.0, 10559.683209503728, 10644.680659580225, 10652.201084918584, 10653.627123050734, 10655.627053053184, 10410.583576656925, 10863.29388589747, 10867.184961127947, 10876.782464350668, 10882.564697412094, 43796.0, 44149.55850441487, 44207.0, 44238.33642495365, 44270.0, 3699.056740531147, 3813.84744610218, 4123.8556650517085, 4141.64795992341, 4469.396631454777, 33152.83423582867, 34176.48735268948, 34273.657263427296, 34285.485717714255, 34473.82763086194, 3962.980185099085, 3978.6021397860272, 4063.8780836574656, 4097.97951010243, 4103.199876024177, 322463.0, 322851.0, 324111.0, 324478.0, 324557.0, 61133.38866611277, 61673.38326616676, 74047.0, 75380.62309688417, 75474.0, 70876.64561677261, 71131.644341779, 71154.0, 71460.64269678618, 71519.2848071523, 18416.815831841777, 18615.255389784255, 18634.068296585116, 18644.34744783926, 18644.813551864307, 13931.860681393056, 14032.158070515801, 14044.297785110704, 14178.0, 14225.07537010091, 22192.55614887693, 22529.211477598205, 22614.547709045728, 22677.2062977795, 22682.206122785625, 8026.478278911852, 8291.33669306454, 8299.336053115736, 8308.418410711192, 8342.0, 1119.9011617480146, 1139.401814047615, 1148.787474317254, 1157.253571446429, 1182.562451892788, 30702.84648576743, 30734.69265307363, 30941.226469338446, 31364.686353136633, 31459.213519661735, 1141.828725691153, 1161.7444162284285, 1184.4492311075392, 1258.0941721960173, 1283.2621242785435, 14445.422183112558, 16442.177891105395, 16523.83476165223, 16613.0, 16697.83302166987, 378091.0, 378774.0, 379996.0, 384829.0, 390449.0, 59230.703846480494, 59494.10758838621, 59554.70222648859, 59563.0, 59625.0, 1411.7105993271355, 1413.0754321190643, 1473.152937061194, 1500.924953752308, 1572.6776010917733, 28631.570526441705, 28828.855855720587, 29092.56361154542, 30921.536176956914, 31485.37029259356, 49946.500534994186, 50393.0, 50425.49574504355, 50453.24320135127, 50483.242751358026, 285016.0, 286256.0, 286973.0, 287250.0, 287348.0, 36245.81877090598, 36281.81859090688, 36618.8169059153, 50232.246516303014, 50498.74750626223, 50845.0, 51258.74370628123, 51812.22281665703, 52190.73904630452, 52956.0, 42185.57814421816, 43635.78182109069, 43976.78011609922, 44526.77736611296, 44568.55431445644, 42186.0, 42686.0, 43151.0, 44361.0, 44510.0, 43026.0, 43108.568914310454, 43347.783261084754, 43877.56122438863, 43919.34120988124, 41836.95407614834, 43348.56651433445, 44786.77606611946, 44800.327995079446, 44926.0, 31929.36141277208, 32299.838500807346, 32441.67558324481, 35049.64950350464, 36951.03583789635], "unit": "ms", "name": "ext.html", "value": 21404.771017556082}, {"lowerIsBetter": false, "replicates": [385.90545316397487, 385.9343911535039, 386.6404244053031, 386.7698719262039, 386.9787161706106, 12624.684382890433, 14709.705805883876, 18022.5494362641, 20546.280880169197, 20556.38330850075, 18827.058647067624, 18862.71705924412, 19170.808291917077, 19228.13473393697, 19261.807381926177, 5612.41069687683, 5728.369879313273, 5733.025385684444, 5756.251687280647, 5763.221965034709, 4406.118776244748, 4533.909321813562, 5724.656520608777, 6198.256209254873, 6394.872102557946, 6168.383161683841, 6306.810795676137, 6337.87324253517, 6356.523260755439, 6363.363663633622, 6221.0, 6222.81331560054, 6236.18929539157, 6248.156498872662, 6248.625082495042, 5574.470425309589, 5622.044252477089, 5684.346300175492, 5685.857853553653, 5778.306603207642, 22360.0, 22520.0, 22746.54506909869, 23325.650115248285, 23357.766422335728, 2203.6804663323887, 2209.171560664751, 2239.428945618873, 2244.494988627546, 2386.9403264918515, 11069.833952490719, 11224.326540407601, 11300.717482062932, 11441.713957151054, 11478.713032174262, 1784.1168621532424, 1790.8925464472172, 1839.8528117750548, 1843.861710371714, 1866.7106598477121, 60180.0, 62026.0, 63818.68090659563, 70509.29490705079, 72637.27362726358, 7631.275028872248, 7781.688732450752, 7879.409044321699, 7905.841883162305, 7965.322947549407, 9558.0, 9561.0, 9564.234861211226, 9581.233501319877, 9581.568829402693, 6346.27017892939, 6465.967670161714, 6472.158619379545, 6477.255115661759, 6507.0, 6853.931460685429, 6857.451403887677, 6860.794176174722, 6920.5847649141215, 6921.515493915478, 3646.5441819772527, 3700.8149592520267, 3702.370596998463, 3717.20080182762, 3717.851285948586, 1220.4263995921901, 1258.5280519805074, 1272.0650322013312, 1272.5609664665676, 1309.8100775387516, 509.5388673250714, 514.7477735909378, 540.9810656627, 542.7069382533388, 555.419586532075, 12459.750804983849, 12893.48426062947, 12959.870401295866, 12973.286469244093, 12989.025823063308, 550.8319962411503, 553.8643032457073, 563.8562166647561, 567.5544697412512, 570.2188002436689, 7263.346298833025, 7301.306375894282, 7318.451116166309, 7434.442416818761, 7517.323440890346, 594627.0, 670097.0, 672575.0, 679747.0, 680988.0, 81223.0, 82567.5871620638, 83403.0, 85667.14332856591, 87545.5622721895, 590.3004939147124, 594.3937184072222, 596.0701305962712, 596.9462748352669, 597.357840321656, 11774.705632359259, 12304.26174429537, 12358.382080896099, 12400.441980110736, 12501.249925004166, 35999.64000359963, 36254.274914502086, 36299.27401452009, 36611.63388366082, 38869.2226155481, 45960.770196148806, 46661.53338466708, 46912.29631555598, 47451.0, 48043.279350810466, 7220.277972202738, 7635.083789945242, 7647.502912310904, 7651.196624354363, 7652.73215437457, 13175.538856139989, 13280.0, 13285.933570332087, 13452.46190152422, 13529.661758456117, 8756.956215218883, 11703.2978021319, 11848.111391645489, 11849.229800062969, 11855.52577896909, 11660.06719462458, 11779.941100294443, 11921.940390297994, 12037.819432708342, 12079.698007549881, 12257.509699612272, 12430.564930227398, 12452.439640216031, 12551.560695375618, 12595.74808503843, 7671.769846904602, 7702.460827742004, 7737.497062690907, 7742.651580678996, 7756.495827771177, 11752.823707644222, 11848.70378240551, 12010.279383237033, 12139.39303034827, 12382.876171238173], "unit": "ms", "name": "jquery.html", "value": 7983.268146512691}, {"lowerIsBetter": false, "replicates": [1518.764591488319, 1520.4526370506617, 1742.6253355528559, 1824.1608859924434, 1843.1797849956765, 7732.690692372305, 8056.879146812795, 8065.0, 8065.153158918317, 8076.91923080769, 8198.631061602227, 8223.794405139859, 8225.6298466569, 8240.258376746091, 8245.0, 2403.351095204294, 2440.5362981033545, 2451.1298489036403, 2457.0908763757434, 2463.8521688698647, 2421.7336093029753, 2431.9513609727796, 2440.9267721968367, 2457.299669594166, 2458.8032957363457, 2160.6542953127505, 2179.1936983316195, 2397.25685037638, 2409.0484258717884, 2451.44842410458, 2393.5452264069772, 2397.1490121007055, 2417.613181890889, 2417.903283868643, 2419.794317483016, 2362.102401087576, 2396.8801559921935, 2400.411899084718, 2401.1715957994465, 2402.915897943564, 7180.24607416219, 7287.817804554876, 7623.390128789683, 7733.072031356217, 7774.922250777477, 5010.874728131789, 5078.339815823919, 5080.288759573663, 5084.822031228926, 5092.0579692756955, 3279.8688052477873, 3320.4521253993303, 3321.7342612590933, 3324.0360295514515, 3342.331533693271, 1131.4682099413262, 1157.39236900627, 1160.3386069940138, 1169.1523645357133, 1191.1483289448, 11224.66326010221, 11285.830712539318, 11286.0, 11288.2662626929, 11327.207095503318, 4613.515580863961, 4625.838095666635, 4665.486796452387, 4675.4155730533685, 4690.7654617269, 9668.516574171263, 9680.322377433513, 9702.320837541445, 9708.077732615531, 9732.172765314886, 5121.667091639031, 5126.128558145124, 5129.256257842667, 5132.589392848563, 5133.358330208724, 4648.209804333272, 4652.116097941381, 4656.604188644003, 4658.697184682985, 4664.230401983703, 3809.5619003814363, 3826.6747326477002, 3853.8843834685003, 3881.902952426212, 3899.2396482685604, 1262.1921969939247, 1275.1520239040933, 1286.3182513267982, 1286.3568215892053, 1286.9871301287055, 1460.2041887171517, 1464.5761265106266, 1475.1591592792006, 1497.0194522587722, 1499.8500149985132, 7899.486533375312, 7902.565358905315, 7921.207879212148, 7921.564313962787, 7939.523628582188, 534.7433232048638, 536.9033573956725, 540.2058973309229, 540.9567234621221, 549.1022178737712, 6081.239845019372, 6187.597806142587, 6397.52018598607, 6405.135306733625, 6435.6138631681315, 9580.329376943686, 9700.175485083846, 9776.951115244377, 9777.9022209777, 9782.902170978341, 9422.528873556295, 9605.087516686044, 9790.118889299998, 9795.118439340351, 9801.264905132144, 7671.0, 7760.146383897767, 7771.484560510759, 7777.066751989684, 7777.883331749915, 7643.617819109134, 7762.805929851573, 7769.572673503011, 7783.766487005399, 7825.373970082266, 8715.651373944971, 8743.606537705691, 8787.91212087871, 8931.19619234272, 8942.955285223532, 8887.911120888966, 9069.138431848964, 9073.682421115229, 9078.0, 9088.04575519561, 7321.890171647536, 7334.266573342623, 7404.370628496638, 7405.8148546286775, 7445.553266804009, 8103.149169337371, 8117.634706438106, 8153.510789352657, 8160.632771525415, 8172.141925097779, 7622.237776222333, 7641.312281894656, 7659.387249019954, 7723.729669461766, 7744.380449563909, 7415.295546923034, 7507.774766757006, 7511.549307041595, 7520.323170914644, 7584.393248540214, 7657.540547567164, 7713.344365728976, 7715.112762032438, 7716.0, 7718.112417072109, 6954.513694455415, 6959.338862808025, 7029.3673569379, 7044.0, 7097.361237488651, 7084.716611335489, 7145.071140751669, 7147.463940204401, 7152.24901385347, 7155.24869888675], "unit": "ms", "name": "mootools.html", "value": 4724.240756029276}, {"lowerIsBetter": false, "replicates": [353.1084012867509, 357.9767315124518, 358.2780696895754, 372.04199187093235, 379.4516923046198, 5917.704114794259, 5929.911051334234, 5941.108833674945, 5944.643321400714, 5970.910436343458, 5616.213730077792, 5663.093904975205, 5709.457601527848, 5796.043652797283, 5857.355690874, 4529.456465224178, 4541.409616749818, 4561.361409402678, 4563.133004729108, 4576.359309696629, 4556.043230921494, 4558.042811009692, 4563.406757121586, 4576.290674945372, 4581.518940511249, 4534.614557762594, 4534.7052441591195, 4540.341650460681, 4540.614047805941, 4542.0, 4442.911141777163, 4591.908161836761, 4607.493175750665, 4616.28447590624, 4622.260438329885, 4067.3288907330257, 4076.4700588923547, 4082.408050832613, 4089.7750623715683, 4096.651784598313, 5658.462446067658, 5660.858478538028, 5673.290838645169, 5680.11958146488, 5680.914786278209, 5361.115415956362, 5364.785408583652, 5370.382406023279, 5373.167159090347, 5378.78484860609, 6163.476104531121, 6279.37206279373, 6280.277768056686, 6282.089097080898, 6287.9056814147825, 4512.390827238347, 4517.661175411858, 4518.864434066983, 4522.751248681321, 4525.117602067598, 6475.805725828232, 6480.773172938924, 6482.9027564586095, 6491.0912472254395, 6495.8700825983215, 4523.683342166017, 4594.816207351735, 4610.124076425468, 4618.214903466419, 4636.536346365404, 4663.696859704109, 4672.696274742131, 4673.275642275453, 4675.2052151134385, 4675.976620116878, 5337.066013447631, 5339.946600534023, 5341.412444631087, 5346.812861549827, 5349.946500535023, 6323.683815809283, 6336.112944187817, 6339.175907132136, 6341.873162536724, 6344.49244060474, 1658.991705041467, 1699.3882202407124, 1702.4552143314268, 1718.484454663621, 1737.8522825559717, 1083.631565267797, 1091.7270682329417, 1104.8729396119388, 1149.1898211760688, 1153.4405813180601, 6007.309159446632, 6032.517398608101, 6033.7586496540525, 6040.395960404012, 6048.848778780566, 6439.195100612424, 6440.162778838721, 6442.871142577123, 6444.871102578016, 6445.258795238514, 6017.338092809788, 6023.819285421357, 6028.698565071729, 6029.758809647565, 6032.607880487754, 3586.8744593939086, 3619.8009109498703, 3622.565292164957, 3622.69207117398, 3623.4202527595457, 6715.059891615178, 6721.6975236114495, 6725.562838415487, 6726.56277341981, 6735.259121496631, 6515.348465153446, 6648.401643852077, 6652.0, 6658.500612453989, 6659.800205993828, 6737.393634572912, 6761.628110453972, 6765.086713293741, 6765.661716914135, 6777.695003724844, 6554.737810487527, 6555.868882622225, 6568.8357791055605, 6569.671516424255, 6572.572782769104, 6343.524235682435, 6478.28738838718, 6480.870382592416, 6483.286838447672, 6490.286068532552, 6744.426723728538, 6747.460203183636, 6748.966255168692, 6749.223839258351, 6757.526973111835, 4590.747508887043, 4605.83879564227, 4626.843289177706, 4629.074185162914, 4630.07398520304, 6583.63789991555, 6587.0448784926875, 6588.110605068446, 6588.209414870246, 6591.242007169045, 6288.842778930563, 6290.0879372491645, 6306.243250809932, 6314.684265786784, 6386.712597933197, 6360.872782544416, 6361.14124593174, 6364.554481186273, 6368.42684158428, 6371.904421433775, 6024.036154215304, 6266.185395898687, 6270.561060725706, 6276.435120839146, 6283.2460104787715, 6032.366601506954, 6273.404026617464, 6374.266959299554, 6382.266039405527, 6383.489320854228, 6212.409821066992, 6214.564980451325, 6216.316205217513, 6217.4715149212825, 6229.688515574113], "unit": "ms", "name": "prototype.html", "value": 4812.564795476425}, {"lowerIsBetter": false, "replicates": [948.8671585977964, 952.4237836109158, 954.5513608603956, 961.1637875048708, 975.3757595139117, 15398.769018464733, 15459.0, 16266.34934602616, 16272.83727162728, 16274.0, 15012.024218425822, 15103.84896151038, 15138.545843624708, 15143.545693629181, 15169.165695886722, 787.4802630264027, 788.2629741192004, 831.4886344897905, 861.1818772166436, 923.7182659288915, 863.0592654007131, 867.5228624256665, 867.6616119713317, 872.9694460693877, 930.0513476254216, 829.8381815545971, 901.0088902207585, 902.9593668284942, 904.9592768325408, 966.1690945786653, 825.0511911301991, 903.5301643145593, 904.0688091266006, 905.7509184974152, 965.2663975378692, 660.9008648702685, 662.2979641579941, 662.3343539742576, 684.4558575932145, 718.9964050179768, 15184.164870932094, 15272.923635381752, 15335.463258785889, 15337.233138343037, 15387.153706546136, 13846.376913038937, 14453.71092578153, 14461.0, 14465.0, 14489.275536223147, 11270.943645281803, 11364.0, 11372.943135284271, 11498.655040348804, 11707.882921170765, 3919.627635374635, 3934.5475270343704, 3937.074787424953, 3941.408788681692, 3963.128111815396, 52697.736511317584, 53112.734436327955, 54948.0, 55128.448715512735, 55131.72434137844, 8495.362847786317, 10797.568097276178, 10977.12183025356, 10990.065844403156, 11096.722581935515, 13064.477420903248, 13182.60452186436, 13184.0, 13219.537316194079, 13223.537176198979, 7523.0, 7534.397248220129, 7624.580648064299, 7771.533707977539, 7792.376609871197, 5034.0686972910125, 5040.319556859777, 5056.696598204119, 5116.386033675983, 5116.846494605168, 3008.8044277122353, 3036.028470889338, 3040.9543856842165, 3043.9543406848916, 3048.527478240876, 467.9836205732851, 473.9905201895943, 478.71516447713554, 479.3504800994672, 483.1110756208593, 647.8866198415353, 764.9513212795505, 774.8605251054864, 788.6608758234014, 790.5217343507211, 8533.53065581399, 8979.775505612412, 9052.094790521027, 9071.0, 9074.364794464323, 622.9221347331584, 680.0717021266036, 773.4315278270467, 802.330054404567, 807.576022588146, 4232.238197124486, 4235.987905744747, 4299.054208074219, 4322.891927701832, 4357.324614684666, 71113.28886711066, 71739.28260717326, 71913.64043179856, 72195.6390218056, 72843.0, 61381.69309153426, 62047.37952620506, 62093.0, 62439.6878015607, 63503.6824815873, 56768.14847777287, 56793.14810277767, 57686.134707979414, 57704.0, 59237.0, 8867.600957956778, 9026.413283136575, 9031.864522032307, 9157.725268241964, 9301.102999523853, 42014.0, 43116.13767724565, 43447.34828977631, 44258.33612495751, 44464.33303500385, 49201.753991229816, 49213.753931230116, 55960.72019640038, 56830.0, 58387.416125839896, 8556.914430855611, 8925.73222803317, 9036.27709783229, 9065.27477801761, 9102.362834601514, 12321.322327271728, 13361.599152025456, 13553.59339219825, 13724.276268542215, 13960.720785584028, 11850.111241657083, 12121.878781212075, 12187.207831490925, 12371.072169587136, 12404.19372740769, 11666.883331166919, 11827.349495777815, 12084.577039803564, 12199.085068619712, 12498.125131240726, 11506.309621422406, 11980.101492387932, 12007.579734709243, 12062.75874482488, 12420.441080151235, 10554.577816887238, 10809.675709728406, 10831.891681083089, 10953.945230273797, 11011.669649910515, 9754.317197796086, 9768.974257703123, 10004.84992725095, 10052.447115408722, 10284.948575257076], "unit": "ms", "name": "yui.html", "value": 6821.701127831171}], "name": "dromaeo_css", "value": 7291.463027263177}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 104.0, 111.0, 112.0, 109.0, 98.0, 108.0, 108.0, 104.0], "unit": "ms", "name": "ai-astar", "value": 107.2}, {"lowerIsBetter": true, "replicates": [152.0, 127.0, 145.0, 166.0, 127.0, 146.0, 151.0, 122.0, 167.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.4}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 159.0, 159.0, 159.0, 158.0, 161.0, 158.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 159.6}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 73.0, 141.0, 71.0, 71.0, 72.0, 70.0, 147.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 85.8}, {"lowerIsBetter": true, "replicates": [89.0, 90.0, 144.0, 88.0, 90.0, 90.0, 130.0, 90.0, 90.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 103.4}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 144.0, 145.0, 145.0, 145.0, 145.0, 144.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 144.8}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 58.0, 58.0, 58.0, 59.0, 60.0, 57.0, 55.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [61.0, 61.0, 62.0, 62.0, 63.0, 62.0, 63.0, 64.0, 64.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.5}, {"lowerIsBetter": true, "replicates": [79.0, 77.0, 79.0, 79.0, 80.0, 79.0, 78.0, 79.0, 78.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.7}, {"lowerIsBetter": true, "replicates": [113.0, 114.0, 113.0, 108.0, 115.0, 112.0, 115.0, 108.0, 112.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.4}, {"lowerIsBetter": true, "replicates": [146.0, 139.0, 137.0, 141.0, 135.0, 131.0, 137.0, 128.0, 142.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.5}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 60.0, 62.0, 61.0, 62.0, 61.0, 61.0, 62.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.6}], "name": "kraken", "value": 1457.8}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "Richards", "value": 214.42434799999998}, {"lowerIsBetter": false, "replicates": [171.23287671232876], "unit": "score", "name": "DeltaBlue", "value": 386.12912}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5015.0], "unit": "score", "name": "Decrypt", "value": 53.07696909272185}, {"lowerIsBetter": false, "replicates": [946.073793755913], "unit": "score", "name": "RayTrace", "value": 782.1683729999996}, {"lowerIsBetter": false, "replicates": [551.2679162072767], "unit": "score", "name": "Earley", "value": 1208.9638819999998}, {"lowerIsBetter": false, "replicates": [10531.25], "unit": "score", "name": "Boyer", "value": 63.28432047477742}, {"lowerIsBetter": false, "replicates": [23952.380952380954], "unit": "score", "name": "RegExp", "value": 38.0331709741551}, {"lowerIsBetter": false, "replicates": [531.9148936170212], "unit": "score", "name": "Splay", "value": 153.20307999999997}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23275.431400194346}]} 17:48:28 INFO - RETURN: dromaeo_css: 7291.46 17:48:28 INFO - RETURN: kraken: 1457.80 17:48:28 INFO - RETURN: v8_7: 23275.43 17:48:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23275.43"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7291.46"}}} 17:48:28 INFO - Return code: 0 17:48:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 17:48:28 INFO - # TBPL SUCCESS # 17:48:28 INFO - Running post-action listener: _resource_record_post_action 17:48:28 INFO - Running post-run listener: _resource_record_post_run 17:48:29 INFO - Total resource usage - Wall time: 1219s; CPU: 13.0%; Read bytes: 315392; Write bytes: 499154944; Read time: 64; Write time: 484420 17:48:29 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 234487808; Read time: 0; Write time: 328664 17:48:29 INFO - run-tests - Wall time: 1205s; CPU: 12.0%; Read bytes: 315392; Write bytes: 264667136; Read time: 64; Write time: 155756 17:48:29 INFO - Running post-run listener: _upload_blobber_files 17:48:29 INFO - Blob upload gear active. 17:48:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:48:29 INFO - Copying logs to upload dir... 17:48:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1263.175873 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-13 17:48:29.686746) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-13 17:48:29.690503) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009917 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452726691/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-13 17:48:29.741049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:48:29.741381) ========= 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=1b9da9da7504fe468691862d000001b9-1452733228.749545-149687965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004527 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 17:48:29.791043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 17:48:29.791359) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 17:48:29.791656) ========= ========= Total master_lag: 0.28 =========