builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1450250437.68 results: success (0) buildid: 20151215210108 builduid: 36c99bd1ed974dbdaf281358f7fd063a revision: 48a7f4361f2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.675863) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.676392) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.676752) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006343 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.701259) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.701568) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.735725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.736054) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004602 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.784999) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.785343) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.785759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.786121) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-15 23:20:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.4M=0s 2015-12-15 23:20:38 (40.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170647 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.969391) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:37.969754) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021498 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:38.019780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 23:20:38.020204) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 48a7f4361f2f --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 48a7f4361f2f --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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-15 23:20:38,871 Setting DEBUG logging. 2015-12-15 23:20:38,871 attempt 1/10 2015-12-15 23:20:38,871 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/48a7f4361f2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-15 23:20:39,644 unpacking tar archive at: mozilla-beta-48a7f4361f2f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.056016 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-15 23:20:39.089246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:39.089548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:39.097341) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:39.097598) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-15 23:20:39.098003) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-12-15 23:20:39.098302) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --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', '--system-bits', '64', '--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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:20:40 INFO - MultiFileLogger online at 20151215 23:20:40 in /builds/slave/test-pgo 23:20:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 23:20:40 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 23:20:40 INFO - {'append_to_log': False, 23:20:40 INFO - 'base_work_dir': '/builds/slave/test-pgo', 23:20:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:20:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 23:20:40 INFO - 'branch': 'Mozilla-Beta', 23:20:40 INFO - 'buildbot_json_path': 'buildprops.json', 23:20:40 INFO - 'config_files': ('talos/linux_config.py',), 23:20:40 INFO - 'default_actions': ('clobber', 23:20:40 INFO - 'read-buildbot-config', 23:20:40 INFO - 'download-and-extract', 23:20:40 INFO - 'populate-webroot', 23:20:40 INFO - 'create-virtualenv', 23:20:40 INFO - 'install', 23:20:40 INFO - 'run-tests'), 23:20:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:20:40 INFO - 'download_minidump_stackwalk': True, 23:20:40 INFO - 'download_symbols': 'ondemand', 23:20:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:20:40 INFO - 'tooltool.py': '/tools/tooltool.py', 23:20:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:20:40 INFO - '/tools/misc-python/virtualenv.py')}, 23:20:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:20:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:20:40 INFO - 'installer_path': 'installer.exe', 23:20:40 INFO - 'log_level': 'info', 23:20:40 INFO - 'log_name': 'talos', 23:20:40 INFO - 'log_to_console': True, 23:20:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:20:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:20:40 INFO - 'opt_config_files': (), 23:20:40 INFO - 'pip_index': False, 23:20:40 INFO - 'sps_profile': False, 23:20:40 INFO - 'sps_profile_interval': 0, 23:20:40 INFO - 'suite': 'dromaeojs', 23:20:40 INFO - 'system_bits': '64', 23:20:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:20:40 INFO - 'title': 'talos-linux64-ix-009', 23:20:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:20:40 INFO - 'use_talos_json': True, 23:20:40 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 23:20:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:20:40 INFO - 'work_dir': 'build'} 23:20:40 INFO - ##### 23:20:40 INFO - ##### Running clobber step. 23:20:40 INFO - ##### 23:20:40 INFO - Running pre-action listener: _resource_record_pre_action 23:20:40 INFO - Running main action method: clobber 23:20:40 INFO - rmtree: /builds/slave/test-pgo/build 23:20:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 23:20:40 INFO - Running post-action listener: _resource_record_post_action 23:20:40 INFO - ##### 23:20:40 INFO - ##### Running read-buildbot-config step. 23:20:40 INFO - ##### 23:20:40 INFO - Running pre-action listener: _resource_record_pre_action 23:20:40 INFO - Running main action method: read_buildbot_config 23:20:40 INFO - Using buildbot properties: 23:20:40 INFO - { 23:20:40 INFO - "properties": { 23:20:40 INFO - "buildnumber": 72, 23:20:40 INFO - "product": "firefox", 23:20:40 INFO - "script_repo_revision": "production", 23:20:40 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 23:20:40 INFO - "repository": "", 23:20:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 23:20:40 INFO - "buildid": "20151215210108", 23:20:40 INFO - "slavename": "talos-linux64-ix-009", 23:20:40 INFO - "pgo_build": "True", 23:20:40 INFO - "basedir": "/builds/slave/test-pgo", 23:20:40 INFO - "project": "", 23:20:40 INFO - "platform": "ubuntu64_hw", 23:20:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:20:40 INFO - "slavebuilddir": "test", 23:20:40 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 23:20:40 INFO - "branch": "mozilla-beta", 23:20:40 INFO - "repo_path": "releases/mozilla-beta", 23:20:40 INFO - "stage_platform": "linux64-pgo", 23:20:40 INFO - "builduid": "36c99bd1ed974dbdaf281358f7fd063a", 23:20:40 INFO - "revision": "48a7f4361f2f" 23:20:40 INFO - }, 23:20:40 INFO - "sourcestamp": { 23:20:40 INFO - "repository": "", 23:20:40 INFO - "hasPatch": false, 23:20:40 INFO - "project": "", 23:20:40 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 23:20:40 INFO - "changes": [ 23:20:40 INFO - { 23:20:40 INFO - "category": null, 23:20:40 INFO - "files": [ 23:20:40 INFO - { 23:20:40 INFO - "url": null, 23:20:40 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2" 23:20:40 INFO - } 23:20:40 INFO - ], 23:20:40 INFO - "repository": "", 23:20:40 INFO - "rev": "48a7f4361f2f", 23:20:40 INFO - "who": "sendchange", 23:20:40 INFO - "when": 1450250377, 23:20:40 INFO - "number": 7365057, 23:20:40 INFO - "comments": "Bug 1232887 - _make package MOZ_PKG_PRETTYNAMES=1_ is busted. r=glandium, a=bustage", 23:20:40 INFO - "project": "", 23:20:40 INFO - "at": "Tue 15 Dec 2015 23:19:37", 23:20:40 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 23:20:40 INFO - "revlink": "", 23:20:40 INFO - "properties": [ 23:20:40 INFO - [ 23:20:40 INFO - "buildid", 23:20:40 INFO - "20151215210108", 23:20:40 INFO - "Change" 23:20:40 INFO - ], 23:20:40 INFO - [ 23:20:40 INFO - "builduid", 23:20:40 INFO - "36c99bd1ed974dbdaf281358f7fd063a", 23:20:40 INFO - "Change" 23:20:40 INFO - ], 23:20:40 INFO - [ 23:20:40 INFO - "pgo_build", 23:20:41 INFO - "True", 23:20:41 INFO - "Change" 23:20:41 INFO - ] 23:20:41 INFO - ], 23:20:41 INFO - "revision": "48a7f4361f2f" 23:20:41 INFO - } 23:20:41 INFO - ], 23:20:41 INFO - "revision": "48a7f4361f2f" 23:20:41 INFO - } 23:20:41 INFO - } 23:20:41 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2. 23:20:41 INFO - Running post-action listener: _resource_record_post_action 23:20:41 INFO - ##### 23:20:41 INFO - ##### Running download-and-extract step. 23:20:41 INFO - ##### 23:20:41 INFO - Running pre-action listener: _resource_record_pre_action 23:20:41 INFO - Running main action method: download_and_extract 23:20:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 23:20:41 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')]} 23:20:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/test_packages.json 23:20:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 23:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 23:20:41 INFO - Downloaded 1270 bytes. 23:20:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 23:20:41 INFO - Using the following test package requirements: 23:20:41 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 23:20:41 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 23:20:41 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'jsshell-linux-x86_64.zip'], 23:20:41 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 23:20:41 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 23:20:41 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 23:20:41 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 23:20:41 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 23:20:41 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 23:20:41 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 23:20:41 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 23:20:41 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:20:41 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')]} 23:20:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.common.tests.zip 23:20:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:50 INFO - Downloaded 22571733 bytes. 23:20:50 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 23:20:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 23:20:50 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 23:20:51 INFO - Return code: 0 23:20:51 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 23:20: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')]} 23:20:51 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.common.tests.zip 23:20:51 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:51 INFO - Downloaded 22571733 bytes. 23:20:51 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 23:20:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 23:20:51 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 23:20:53 INFO - Return code: 0 23:20: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')]} 23:20:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 23:20:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:56 INFO - Downloaded 11131970 bytes. 23:20:56 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 23:20:56 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 23:20:56 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 23:20:57 INFO - Return code: 0 23:20:57 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')]} 23:20:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2 23:20:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/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 23:21:05 INFO - Downloaded 56640798 bytes. 23:21:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2 23:21:05 INFO - mkdir: /builds/slave/test-pgo/properties 23:21:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 23:21:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 23:21:05 INFO - Contents: 23:21:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2 23:21:05 INFO - Running post-action listener: _resource_record_post_action 23:21:05 INFO - Running post-action listener: set_extra_try_arguments 23:21:05 INFO - ##### 23:21:05 INFO - ##### Running populate-webroot step. 23:21:05 INFO - ##### 23:21:05 INFO - Running pre-action listener: _resource_record_pre_action 23:21:05 INFO - Running main action method: populate_webroot 23:21:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:21:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:21:05 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 23:21:05 INFO - '../fennec_ids.txt'], 23:21:05 INFO - 'tests': ['tcheck2']}, 23:21:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:21:05 INFO - '--tppagecycles', 23:21:05 INFO - '7'], 23:21:05 INFO - 'tests': ['tsvgm']}}, 23:21:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 23:21:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:21:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 23:21:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 23:21:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:21:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'tests': ['damp', 'tps']}, 23:21:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['damp', 'tps']}, 23:21:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 23:21:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:21:05 INFO - 'other': {'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'other_l64': {'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'other_nol64': {'tests': ['a11yr', 23:21:05 INFO - 'ts_paint', 23:21:05 INFO - 'tpaint', 23:21:05 INFO - 'sessionrestore', 23:21:05 INFO - 'sessionrestore_no_auto_restore']}, 23:21:05 INFO - 'svgr': {'tests': ['tsvgx', 23:21:05 INFO - 'tsvgr_opacity', 23:21:05 INFO - 'tart', 23:21:05 INFO - 'tscrollx', 23:21:05 INFO - 'cart']}, 23:21:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['tsvgx', 23:21:05 INFO - 'tsvgr_opacity', 23:21:05 INFO - 'tart', 23:21:05 INFO - 'tscrollx', 23:21:05 INFO - 'cart']}, 23:21:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'tests': ['tp5o']}, 23:21:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'talos_options': ['--e10s'], 23:21:05 INFO - 'tests': ['tp5o']}, 23:21:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'talos_options': ['--xperf_path', 23:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:05 INFO - 'tests': ['tp5n']}, 23:21:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:21:05 INFO - 'talos_options': ['--e10s', 23:21:05 INFO - '--xperf_path', 23:21:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:21:05 INFO - 'tests': ['tp5n']}}, 23:21:05 INFO - 'talos.zip': {'path': '', 23:21:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:21:05 INFO - Running post-action listener: _resource_record_post_action 23:21:05 INFO - ##### 23:21:05 INFO - ##### Running create-virtualenv step. 23:21:05 INFO - ##### 23:21:05 INFO - Running pre-action listener: _resource_record_pre_action 23:21:05 INFO - Running main action method: create_virtualenv 23:21:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 23:21:05 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 23:21:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 23:21:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:21:05 INFO - Using real prefix '/usr' 23:21:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 23:21:06 INFO - Installing distribute.............................................................................................................................................................................................done. 23:21:07 INFO - Installing pip.................done. 23:21:08 INFO - Return code: 0 23:21:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 23:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:08 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:08 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 23:21:08 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 23:21:08 INFO - Using env: {'DISPLAY': ':0', 23:21:08 INFO - 'HOME': '/home/cltbld', 23:21:08 INFO - 'LANG': 'en_US.UTF-8', 23:21:08 INFO - 'LANGUAGE': 'en_US:en', 23:21:08 INFO - 'LOGNAME': 'cltbld', 23:21:08 INFO - 'MAIL': '/var/mail/cltbld', 23:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:08 INFO - 'MOZ_NO_REMOTE': '1', 23:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:08 INFO - 'NO_EM_RESTART': '1', 23:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:08 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:08 INFO - 'SHELL': '/bin/bash', 23:21:08 INFO - 'SHLVL': '1', 23:21:08 INFO - 'TERM': 'linux', 23:21:08 INFO - 'TMOUT': '86400', 23:21:08 INFO - 'USER': 'cltbld', 23:21:08 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:08 INFO - '_': '/tools/buildbot/bin/python'} 23:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:08 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 23:21:08 INFO - Cleaning up... 23:21:08 INFO - Return code: 0 23:21:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 23:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:08 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:08 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 23:21:08 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 23:21:08 INFO - Using env: {'DISPLAY': ':0', 23:21:08 INFO - 'HOME': '/home/cltbld', 23:21:08 INFO - 'LANG': 'en_US.UTF-8', 23:21:08 INFO - 'LANGUAGE': 'en_US:en', 23:21:08 INFO - 'LOGNAME': 'cltbld', 23:21:08 INFO - 'MAIL': '/var/mail/cltbld', 23:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:08 INFO - 'MOZ_NO_REMOTE': '1', 23:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:08 INFO - 'NO_EM_RESTART': '1', 23:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:08 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:08 INFO - 'SHELL': '/bin/bash', 23:21:08 INFO - 'SHLVL': '1', 23:21:08 INFO - 'TERM': 'linux', 23:21:08 INFO - 'TMOUT': '86400', 23:21:08 INFO - 'USER': 'cltbld', 23:21:08 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:08 INFO - '_': '/tools/buildbot/bin/python'} 23:21:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:08 INFO - Downloading/unpacking psutil>=0.7.1 23:21: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 23:21: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 23:21: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 23:21: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 23:21: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 23:21: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 23:21:10 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 23:21:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 23:21:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 23:21:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:10 INFO - Installing collected packages: psutil 23:21:10 INFO - Running setup.py install for psutil 23:21:10 INFO - building 'psutil._psutil_linux' extension 23:21:10 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 23:21:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:21:10 INFO - building 'psutil._psutil_posix' extension 23:21:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:21:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:21:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:21:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:21:10 INFO - Successfully installed psutil 23:21:10 INFO - Cleaning up... 23:21:10 INFO - Return code: 0 23:21:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 23:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:10 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:10 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 23:21:10 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 23:21:10 INFO - Using env: {'DISPLAY': ':0', 23:21:10 INFO - 'HOME': '/home/cltbld', 23:21:10 INFO - 'LANG': 'en_US.UTF-8', 23:21:10 INFO - 'LANGUAGE': 'en_US:en', 23:21:10 INFO - 'LOGNAME': 'cltbld', 23:21:10 INFO - 'MAIL': '/var/mail/cltbld', 23:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:10 INFO - 'MOZ_NO_REMOTE': '1', 23:21:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:10 INFO - 'NO_EM_RESTART': '1', 23:21:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:10 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:10 INFO - 'SHELL': '/bin/bash', 23:21:10 INFO - 'SHLVL': '1', 23:21:10 INFO - 'TERM': 'linux', 23:21:10 INFO - 'TMOUT': '86400', 23:21:10 INFO - 'USER': 'cltbld', 23:21:10 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:10 INFO - '_': '/tools/buildbot/bin/python'} 23:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:21:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 23:21:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:21:12 INFO - Installing collected packages: mozsystemmonitor 23:21:12 INFO - Running setup.py install for mozsystemmonitor 23:21:12 INFO - Successfully installed mozsystemmonitor 23:21:12 INFO - Cleaning up... 23:21:12 INFO - Return code: 0 23:21:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 23:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:12 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:12 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 23:21:12 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 23:21:12 INFO - Using env: {'DISPLAY': ':0', 23:21:12 INFO - 'HOME': '/home/cltbld', 23:21:12 INFO - 'LANG': 'en_US.UTF-8', 23:21:12 INFO - 'LANGUAGE': 'en_US:en', 23:21:12 INFO - 'LOGNAME': 'cltbld', 23:21:12 INFO - 'MAIL': '/var/mail/cltbld', 23:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:12 INFO - 'MOZ_NO_REMOTE': '1', 23:21:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:12 INFO - 'NO_EM_RESTART': '1', 23:21:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:12 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:12 INFO - 'SHELL': '/bin/bash', 23:21:12 INFO - 'SHLVL': '1', 23:21:12 INFO - 'TERM': 'linux', 23:21:12 INFO - 'TMOUT': '86400', 23:21:12 INFO - 'USER': 'cltbld', 23:21:12 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:12 INFO - '_': '/tools/buildbot/bin/python'} 23:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:13 INFO - Downloading/unpacking blobuploader==1.2.4 23:21:13 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 23:21:13 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 23:21:13 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 23:21:13 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 23:21:13 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 23:21:13 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 23:21:14 INFO - Downloading blobuploader-1.2.4.tar.gz 23:21:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 23:21:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:21:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 23:21:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 23:21:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:21:15 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 23:21:15 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 23:21:15 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 23:21:15 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 23:21:15 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 23:21:15 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 23:21:15 INFO - Downloading docopt-0.6.1.tar.gz 23:21:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 23:21:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 23:21:15 INFO - Installing collected packages: blobuploader, requests, docopt 23:21:15 INFO - Running setup.py install for blobuploader 23:21:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:21:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 23:21:15 INFO - Running setup.py install for requests 23:21:15 INFO - Running setup.py install for docopt 23:21:16 INFO - Successfully installed blobuploader requests docopt 23:21:16 INFO - Cleaning up... 23:21:16 INFO - Return code: 0 23:21:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:16 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:16 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 23:21:16 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 23:21:16 INFO - Using env: {'DISPLAY': ':0', 23:21:16 INFO - 'HOME': '/home/cltbld', 23:21:16 INFO - 'LANG': 'en_US.UTF-8', 23:21:16 INFO - 'LANGUAGE': 'en_US:en', 23:21:16 INFO - 'LOGNAME': 'cltbld', 23:21:16 INFO - 'MAIL': '/var/mail/cltbld', 23:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:16 INFO - 'MOZ_NO_REMOTE': '1', 23:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:16 INFO - 'NO_EM_RESTART': '1', 23:21:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:16 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:16 INFO - 'SHELL': '/bin/bash', 23:21:16 INFO - 'SHLVL': '1', 23:21:16 INFO - 'TERM': 'linux', 23:21:16 INFO - 'TMOUT': '86400', 23:21:16 INFO - 'USER': 'cltbld', 23:21:16 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:16 INFO - '_': '/tools/buildbot/bin/python'} 23:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:21:16 INFO - Running setup.py (path:/tmp/pip-gIDXNY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:21:16 INFO - Running setup.py (path:/tmp/pip-1Be7L2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:21:16 INFO - Running setup.py (path:/tmp/pip-bBYox5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:21:16 INFO - Running setup.py (path:/tmp/pip-UFtAfa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 23:21:16 INFO - Running setup.py (path:/tmp/pip-c9tNYM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:21:16 INFO - Running setup.py (path:/tmp/pip-uEh3jx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:21:16 INFO - Running setup.py (path:/tmp/pip-Zod8cZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:21:16 INFO - Running setup.py (path:/tmp/pip-HHTOXN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 23:21:16 INFO - Running setup.py (path:/tmp/pip-C0aD14-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 23:21:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 23:21:16 INFO - Running setup.py (path:/tmp/pip-GqBjNp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:21:17 INFO - Running setup.py (path:/tmp/pip-OueySj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:21:17 INFO - Running setup.py (path:/tmp/pip-xZXjXD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:21:17 INFO - Running setup.py (path:/tmp/pip-ACSTEU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:21:17 INFO - Running setup.py (path:/tmp/pip-xTI3Rr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:21:17 INFO - Running setup.py (path:/tmp/pip-KbedfE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 23:21:17 INFO - Running setup.py (path:/tmp/pip-l5ziuM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 23:21:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 23:21:17 INFO - Running setup.py (path:/tmp/pip-zxegNP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 23:21:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:21:17 INFO - Running setup.py install for manifestparser 23:21:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 23:21:17 INFO - Running setup.py install for mozcrash 23:21:17 INFO - Running setup.py install for mozdebug 23:21:17 INFO - Running setup.py install for mozdevice 23:21:17 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 23:21:17 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 23:21:17 INFO - Running setup.py install for mozfile 23:21:18 INFO - Running setup.py install for mozhttpd 23:21:18 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for mozinfo 23:21:18 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for mozInstall 23:21:18 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for mozleak 23:21:18 INFO - Running setup.py install for mozlog 23:21:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for moznetwork 23:21:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for mozprocess 23:21:18 INFO - Running setup.py install for mozprofile 23:21:18 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 23:21:18 INFO - Running setup.py install for mozrunner 23:21:19 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 23:21:19 INFO - Running setup.py install for mozscreenshot 23:21:19 INFO - Running setup.py install for moztest 23:21:19 INFO - Running setup.py install for mozversion 23:21:19 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 23:21:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:21:19 INFO - Cleaning up... 23:21:19 INFO - Return code: 0 23:21:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:19 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:19 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 23:21:19 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 23:21:19 INFO - Using env: {'DISPLAY': ':0', 23:21:19 INFO - 'HOME': '/home/cltbld', 23:21:19 INFO - 'LANG': 'en_US.UTF-8', 23:21:19 INFO - 'LANGUAGE': 'en_US:en', 23:21:19 INFO - 'LOGNAME': 'cltbld', 23:21:19 INFO - 'MAIL': '/var/mail/cltbld', 23:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:19 INFO - 'MOZ_NO_REMOTE': '1', 23:21:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:19 INFO - 'NO_EM_RESTART': '1', 23:21:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:19 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:19 INFO - 'SHELL': '/bin/bash', 23:21:19 INFO - 'SHLVL': '1', 23:21:19 INFO - 'TERM': 'linux', 23:21:19 INFO - 'TMOUT': '86400', 23:21:19 INFO - 'USER': 'cltbld', 23:21:19 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:19 INFO - '_': '/tools/buildbot/bin/python'} 23:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:21:19 INFO - Running setup.py (path:/tmp/pip-xqRgmT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:21:19 INFO - Running setup.py (path:/tmp/pip-47_RGe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:21:19 INFO - Running setup.py (path:/tmp/pip-zpgvlz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:21:19 INFO - Running setup.py (path:/tmp/pip-GZjbuc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:21:19 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)) 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 23:21:19 INFO - Running setup.py (path:/tmp/pip-sNKkna-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 23:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 23:21:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:21:19 INFO - Running setup.py (path:/tmp/pip-IiJ20A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:21:20 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)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:21:20 INFO - Running setup.py (path:/tmp/pip-83sZ5n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:21:20 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)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:21:20 INFO - Running setup.py (path:/tmp/pip-bFbPhd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:21:20 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)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 23:21:20 INFO - Running setup.py (path:/tmp/pip-m174ML-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 23:21:20 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)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 23:21:20 INFO - Running setup.py (path:/tmp/pip-wtmWEa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 23:21:20 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)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:21:20 INFO - Running setup.py (path:/tmp/pip-jBPIun-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:21:20 INFO - Running setup.py (path:/tmp/pip-9dIJxw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:21:20 INFO - Running setup.py (path:/tmp/pip-KG72No-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:21:20 INFO - Running setup.py (path:/tmp/pip-QaftRe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:21:20 INFO - Running setup.py (path:/tmp/pip-Sth1wI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 23:21:20 INFO - Running setup.py (path:/tmp/pip-L25Ydg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 23:21:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 23:21:20 INFO - Running setup.py (path:/tmp/pip-rGlVFw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 23:21:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 23:21:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 23:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:22 INFO - Downloading blessings-1.5.1.tar.gz 23:21:22 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 23:21:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 23:21:22 INFO - Installing collected packages: blessings 23:21:22 INFO - Running setup.py install for blessings 23:21:22 INFO - Successfully installed blessings 23:21:22 INFO - Cleaning up... 23:21:22 INFO - Return code: 0 23:21:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 23:21:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 23:21:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 23:21:22 INFO - Reading from file tmpfile_stdout 23:21:22 INFO - Current package versions: 23:21:22 INFO - argparse == 1.2.1 23:21:22 INFO - blessings == 1.5.1 23:21:22 INFO - blobuploader == 1.2.4 23:21:22 INFO - docopt == 0.6.1 23:21:22 INFO - manifestparser == 1.1 23:21:22 INFO - mozInstall == 1.12 23:21:22 INFO - mozcrash == 0.16 23:21:22 INFO - mozdebug == 0.1 23:21:22 INFO - mozdevice == 0.46 23:21:22 INFO - mozfile == 1.2 23:21:22 INFO - mozhttpd == 0.7 23:21:22 INFO - mozinfo == 0.8 23:21:22 INFO - mozleak == 0.1 23:21:22 INFO - mozlog == 3.0 23:21:22 INFO - moznetwork == 0.27 23:21:22 INFO - mozprocess == 0.22 23:21:22 INFO - mozprofile == 0.27 23:21:22 INFO - mozrunner == 6.11 23:21:22 INFO - mozscreenshot == 0.1 23:21:22 INFO - mozsystemmonitor == 0.0 23:21:22 INFO - moztest == 0.7 23:21:22 INFO - mozversion == 1.4 23:21:22 INFO - psutil == 3.1.1 23:21:22 INFO - requests == 1.2.3 23:21:22 INFO - wsgiref == 0.1.2 23:21:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:22 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:22 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 23:21:22 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 23:21:22 INFO - Using env: {'DISPLAY': ':0', 23:21:22 INFO - 'HOME': '/home/cltbld', 23:21:22 INFO - 'LANG': 'en_US.UTF-8', 23:21:22 INFO - 'LANGUAGE': 'en_US:en', 23:21:22 INFO - 'LOGNAME': 'cltbld', 23:21:22 INFO - 'MAIL': '/var/mail/cltbld', 23:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:22 INFO - 'MOZ_NO_REMOTE': '1', 23:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:22 INFO - 'NO_EM_RESTART': '1', 23:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:22 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:22 INFO - 'SHELL': '/bin/bash', 23:21:22 INFO - 'SHLVL': '1', 23:21:22 INFO - 'TERM': 'linux', 23:21:22 INFO - 'TMOUT': '86400', 23:21:22 INFO - 'USER': 'cltbld', 23:21:22 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:22 INFO - '_': '/tools/buildbot/bin/python'} 23:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 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)) 23:21:23 INFO - Cleaning up... 23:21:23 INFO - Return code: 0 23:21:23 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 23:21: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')]} 23:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21: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')]} 23:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:23 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:23 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 23:21:23 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 23:21:23 INFO - Using env: {'DISPLAY': ':0', 23:21:23 INFO - 'HOME': '/home/cltbld', 23:21:23 INFO - 'LANG': 'en_US.UTF-8', 23:21:23 INFO - 'LANGUAGE': 'en_US:en', 23:21:23 INFO - 'LOGNAME': 'cltbld', 23:21:23 INFO - 'MAIL': '/var/mail/cltbld', 23:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:23 INFO - 'MOZ_NO_REMOTE': '1', 23:21:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:23 INFO - 'NO_EM_RESTART': '1', 23:21:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:23 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:23 INFO - 'SHELL': '/bin/bash', 23:21:23 INFO - 'SHLVL': '1', 23:21:23 INFO - 'TERM': 'linux', 23:21:23 INFO - 'TMOUT': '86400', 23:21:23 INFO - 'USER': 'cltbld', 23:21:23 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:23 INFO - '_': '/tools/buildbot/bin/python'} 23:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:23 INFO - Downloading/unpacking jsonschema 23:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:21:25 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 23:21:25 INFO - Downloading/unpacking functools32 (from jsonschema) 23:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:25 INFO - Downloading functools32-3.2.3-2.tar.gz 23:21:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 23:21:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 23:21:25 INFO - warning: no files found matching '*.txt' 23:21:25 INFO - no previously-included directories found matching 'build' 23:21:25 INFO - no previously-included directories found matching 'dist' 23:21:25 INFO - no previously-included directories found matching '.git*' 23:21:25 INFO - Installing collected packages: jsonschema, functools32 23:21:25 INFO - Running setup.py install for functools32 23:21:25 INFO - warning: no files found matching '*.txt' 23:21:25 INFO - no previously-included directories found matching 'build' 23:21:25 INFO - no previously-included directories found matching 'dist' 23:21:25 INFO - no previously-included directories found matching '.git*' 23:21:25 INFO - Successfully installed jsonschema functools32 23:21:25 INFO - Cleaning up... 23:21:25 INFO - Return code: 0 23:21:25 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 23:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:21:25 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 0x7f0916fc6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1502600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, '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': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', '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 23:21:25 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 23:21:25 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 23:21:25 INFO - Using env: {'DISPLAY': ':0', 23:21:25 INFO - 'HOME': '/home/cltbld', 23:21:25 INFO - 'LANG': 'en_US.UTF-8', 23:21:25 INFO - 'LANGUAGE': 'en_US:en', 23:21:25 INFO - 'LOGNAME': 'cltbld', 23:21:25 INFO - 'MAIL': '/var/mail/cltbld', 23:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:25 INFO - 'MOZ_NO_REMOTE': '1', 23:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:25 INFO - 'NO_EM_RESTART': '1', 23:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:25 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:25 INFO - 'SHELL': '/bin/bash', 23:21:25 INFO - 'SHLVL': '1', 23:21:25 INFO - 'TERM': 'linux', 23:21:25 INFO - 'TMOUT': '86400', 23:21:25 INFO - 'USER': 'cltbld', 23:21:25 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:25 INFO - '_': '/tools/buildbot/bin/python'} 23:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:21:25 INFO - Downloading/unpacking flake8 23:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:21:27 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 23:21:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:21:27 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 23:21:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:28 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 23:21:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:21:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 23:21:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 23:21:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:21:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:21:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:21:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:21:28 INFO - no previously-included directories found matching 'docs/_build' 23:21:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:21:28 INFO - Running setup.py install for pep8 23:21:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:21:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:21:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:21:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:21:28 INFO - no previously-included directories found matching 'docs/_build' 23:21:28 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 23:21:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:21:28 INFO - Cleaning up... 23:21:28 INFO - Return code: 0 23:21:28 INFO - Running post-action listener: _resource_record_post_action 23:21:28 INFO - Running post-action listener: _start_resource_monitoring 23:21:28 INFO - Starting resource monitoring. 23:21:28 INFO - ##### 23:21:28 INFO - ##### Running install step. 23:21:28 INFO - ##### 23:21:28 INFO - Running pre-action listener: _resource_record_pre_action 23:21:28 INFO - Running main action method: install 23:21:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 23:21:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 23:21:28 INFO - Reading from file tmpfile_stdout 23:21:28 INFO - Detecting whether we're running mozinstall >=1.0... 23:21:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 23:21:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 23:21:28 INFO - Reading from file tmpfile_stdout 23:21:28 INFO - Output received: 23:21:28 INFO - Usage: mozinstall [options] installer 23:21:28 INFO - Options: 23:21:28 INFO - -h, --help show this help message and exit 23:21:28 INFO - -d DEST, --destination=DEST 23:21:28 INFO - Directory to install application into. [default: 23:21:28 INFO - "/builds/slave/test-pgo"] 23:21:28 INFO - --app=APP Application being installed. [default: firefox] 23:21:28 INFO - mkdir: /builds/slave/test-pgo/build/application 23:21:28 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'] 23:21:28 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 23:21:43 INFO - Reading from file tmpfile_stdout 23:21:43 INFO - Output received: 23:21:43 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 23:21:43 INFO - Running post-action listener: _resource_record_post_action 23:21:43 INFO - ##### 23:21:43 INFO - ##### Running run-tests step. 23:21:43 INFO - ##### 23:21:43 INFO - Running pre-action listener: _resource_record_pre_action 23:21:43 INFO - Running main action method: run_tests 23:21:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 23:21:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 23:21:43 INFO - Python 2.7.3 23:21:43 INFO - Return code: 0 23:21:43 INFO - grabbing minidump binary from tooltool 23:21:43 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')]} 23:21:43 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 0x1591350>, '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 0x1592250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15926e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 23:21:43 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 23:21:43 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 23:21:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:21:43 INFO - Return code: 0 23:21:43 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 23:21:43 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 23:21:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 23:21:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 23:21:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 23:21:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 23:21:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 23:21:44 INFO - Return code: 0 23:21:44 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/1450242068/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 23:21:44 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/1450242068/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost 23:21:44 INFO - Using env: {'DISPLAY': ':0', 23:21:44 INFO - 'HOME': '/home/cltbld', 23:21:44 INFO - 'LANG': 'en_US.UTF-8', 23:21:44 INFO - 'LANGUAGE': 'en_US:en', 23:21:44 INFO - 'LOGNAME': 'cltbld', 23:21:44 INFO - 'MAIL': '/var/mail/cltbld', 23:21:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 23:21:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 23:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:21:44 INFO - 'MOZ_NO_REMOTE': '1', 23:21:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 23:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:21:44 INFO - 'NO_EM_RESTART': '1', 23:21:44 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:21:44 INFO - 'PWD': '/builds/slave/test-pgo', 23:21:44 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 23:21:44 INFO - 'SHELL': '/bin/bash', 23:21:44 INFO - 'SHLVL': '1', 23:21:44 INFO - 'TERM': 'linux', 23:21:44 INFO - 'TMOUT': '86400', 23:21:44 INFO - 'USER': 'cltbld', 23:21:44 INFO - 'XDG_SESSION_COOKIE': 'a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242', 23:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:21:44 INFO - '_': '/tools/buildbot/bin/python'} 23:21:44 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/1450242068/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost'] with output_timeout 3600 23:21:44 INFO - mozversion INFO | application_buildid: 20151215210108 23:21:44 INFO - mozversion INFO | application_changeset: 48a7f4361f2f0bda1bb157468a1b7f705d1c408a 23:21:44 INFO - mozversion INFO | application_display_name: Nightly 23:21:44 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:21:44 INFO - mozversion INFO | application_name: Firefox 23:21:44 INFO - mozversion INFO | application_remotingname: firefox 23:21:44 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:21:44 INFO - mozversion INFO | application_vendor: Mozilla 23:21:44 INFO - mozversion INFO | application_version: 44.0 23:21:44 INFO - mozversion INFO | platform_buildid: 20151215210108 23:21:44 INFO - mozversion INFO | platform_changeset: 48a7f4361f2f0bda1bb157468a1b7f705d1c408a 23:21:44 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:21:44 INFO - mozversion INFO | platform_version: 44.0 23:21:44 INFO - 2015-12-15 23:21:44,229 DEBUG : using testdate: 1450250504 23:21:44 INFO - 2015-12-15 23:21:44,229 DEBUG : actual date: 1450250504 23:21:44 INFO - 2015-12-15 23:21:44,309 INFO : starting webserver on 'localhost:44289' 23:21:44 INFO - 2015-12-15 23:21:44,310 INFO : Starting test suite talos-linux64-ix-009 23:21:44 INFO - 2015-12-15 23:21:44,310 INFO : Starting test dromaeo_css 23:21:44 INFO - 2015-12-15 23:21:44,310 DEBUG : operating with platform_type : linux_ 23:21:44 INFO - 2015-12-15 23:21:44,311 INFO : Initialising browser for dromaeo_css test... 23:21:44 INFO - 2015-12-15 23:21:44,326 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFuU4Tu/profile http://localhost:44289/getInfo.html 23:21:47 INFO - 2015-12-15 23:21:47,641 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:21:47 INFO - 2015-12-15 23:21:47,641 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:21:47 INFO - 2015-12-15 23:21:47,641 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 23:21:47 INFO - 2015-12-15 23:21:47,641 DEBUG : BROWSER_OUTPUT: __metrics 23:21:47 INFO - 2015-12-15 23:21:47,799 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] 23:21:48 INFO - 2015-12-15 23:21:48,745 INFO : Browser initialized. 23:21:48 INFO - 2015-12-15 23:21:48,745 INFO : Running cycle 1/1 for dromaeo_css test... 23:21:48 INFO - 2015-12-15 23:21:48,746 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFuU4Tu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:22:00 INFO - 2015-12-15 23:22:00,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 131645440 23:22:00 INFO - 2015-12-15 23:22:00,431 DEBUG : BROWSER_OUTPUT: 23:22:51 INFO - 2015-12-15 23:22:51,413 DEBUG : BROWSER_OUTPUT: 1450250571412 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] 23:24:59 INFO - 2015-12-15 23:24:59,462 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44289/tests/dromaeo/cssquery-ext.html) 23:25:00 INFO - 2015-12-15 23:25:00,426 DEBUG : BROWSER_OUTPUT: RSS: Main: 212631552 23:25:00 INFO - 2015-12-15 23:25:00,426 DEBUG : BROWSER_OUTPUT: 23:28:02 INFO - 2015-12-15 23:28:02,480 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-ext.html (next: http://localhost:44289/tests/dromaeo/cssquery-jquery.html) 23:28:03 INFO - 2015-12-15 23:28:03,042 DEBUG : BROWSER_OUTPUT: RSS: Main: 222552064 23:28:03 INFO - 2015-12-15 23:28:03,042 DEBUG : BROWSER_OUTPUT: 23:31:04 INFO - 2015-12-15 23:31:04,625 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44289/tests/dromaeo/cssquery-mootools.html) 23:31:05 INFO - 2015-12-15 23:31:05,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 216707072 23:31:05 INFO - 2015-12-15 23:31:05,179 DEBUG : BROWSER_OUTPUT: 23:34:06 INFO - 2015-12-15 23:34:06,927 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44289/tests/dromaeo/cssquery-prototype.html) 23:34:07 INFO - 2015-12-15 23:34:07,480 DEBUG : BROWSER_OUTPUT: RSS: Main: 201113600 23:34:07 INFO - 2015-12-15 23:34:07,481 DEBUG : BROWSER_OUTPUT: 23:37:09 INFO - 2015-12-15 23:37:09,850 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44289/tests/dromaeo/cssquery-yui.html) 23:37:10 INFO - 2015-12-15 23:37:10,411 DEBUG : BROWSER_OUTPUT: RSS: Main: 202280960 23:37:10 INFO - 2015-12-15 23:37:10,412 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/dromaeo/cssquery-yui.html (next: http://localhost:44289/tests/dromaeo/cssquery-dojo.html) 23:40:12 INFO - 2015-12-15 23:40:12,892 DEBUG : BROWSER_OUTPUT: RSS: Main: 212566016 23:40:12 INFO - 2015-12-15 23:40:12,892 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,892 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:40:12 INFO - 2015-12-15 23:40:12,892 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:40:12 INFO - 2015-12-15 23:40:12,892 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:40:12 INFO - 2015-12-15 23:40:12,893 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:40:12 INFO - 2015-12-15 23:40:12,893 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3303.6365999740024;3406.5230867678515;3439.29494453637;3444.5005474206223;3462.948055779163;5740.024195886699;5740.196372507852;5748.798792042279;5749.223854779603;5758.078707406821;5756.683382413976;5773.711314434283;5790.594658373915;5798.362180160179;5821.476067153955;5090.516400941923;5242.082635538775;5263.131583288753;5284.788608455677;5322.0686379883555;5168.819091331804;5169.844904652848;5175.792968281265;5180.974095129519;5185.222216667492;5134.229865520165;5197.896042079156;5225.843224703246;5240.082985477546;5249.343832020997;5177.534021938025;5325.4142044375085;5326.387465441485;5327.200919862013;5328.387235467912;5233.633645644806;5277.683338999673;5279.128943724281;5295.364556253275;5296.576273898079;5310.867228319285;5438.238646589481;5473.917891231635;5600.187972743929;5607;6182.412670796267;6212.627242365473;6213.03697926822;6214.067889816563;6223.502119830403;6229.6573688447115;6243.594166379171;6244.906326405062;6246.625202487865;6254.46837018854;5553.0837411827;5681.602287839853;5692.971535142339;5806.88386232273;5813.04084825999;6276.968615156895;6305.085762564402;6360.968195159087;6363.745450181941;6371.203599550056;6098.817035488943;6133.84665383369;6141.7543298267565;6146.661933593606;6173.043178307369;6003.7898673546215;6085.604435711664;6115.357887421846;6203.875922481525;6222.097795819581;6169.691515424211;6170.907436388457;6172.321044685081;6176.135341052256;6199.194104766441;5906.320773111147;6146.0473626587955;6159.291681456601;6160.168377269101;6164.167837341902;4593.885152871205;4643.976780116078;4660.883477913011;4668.85993420198;4688.367070445514;4173.499180098347;4179.352200408942;4192.035831758712;4207.915841683149;4217.40956266123;5641.633293835969;5731.398203188689;5812.767489300465;5817.854553636193;5818.214541036906;6083.878322433527;6136.7948077883175;6150;6171.413715697002;6178.135061091451;5840.445157710011;5883.970580147072;5886.087656413263;5892.204552385459;5904.055351143882;5236.3716354037;5317.601179911522;5360.035193665178;5361.303030605996;5364.22218778275;6368.203974503187;6371.713272902731;6373.3945275198785;6378.330275321117;6380.170577824853;6205.2553693557065;6209.534284928558;6209.875802484015;6213.285472170758;6217.191765070512;6138.935378312821;6143.723532440961;6263.091851681572;6342.112104305494;6372.107904893412;5989.490893274121;6005.489533389711;6007.158997740407;6032.1856549365275;6065.636061836304;6032.547558933185;6049.818505444844;6052;6057.969710151421;6101.908471373022;5818.709064546841;5963.2844058713235;5966.761329546943;6138.355472675484;6143.815685529441;6068.666223357758;6069.0592958091565;6074;6086.482648974887;6095.847603809941;5697.4302569742695;5922.466977972003;5965.463108320272;5977.432143946318;5978.611390259619;5913.231279933753;6011.188489553854;6016.217891674054;6034.877927254609;6057.848553786014;5835.503982161564;5839.824805255849;5842.474177324061;5852.239208902815;5923.555733319932 23:40:12 INFO - 2015-12-15 23:40:12,896 DEBUG : BROWSER_OUTPUT: |1;ext.html;635.3297271378696;635.6313338263808;640.7917426836278;652.6377860287541;657.6974591687823;44306.33540496891;45243.77378113105;45247.54752452474;45259.32111018333;45318.09363812722;48377.27434088491;48938.51061489384;48975.26537101938;48996.51003489964;49080.75459622697;8223.876641850347;8330.458520196198;9253.861192082124;9453.149216570508;10654.934506549322;10808.567657293737;10948.835767463494;10953.123750099972;10960.232783705143;10960.451977401139;10979.451027448635;11077.612283570077;11090.611828586001;11091.72270693231;11098.778024439507;10946.45267736614;11047.889521104848;11055.834162487568;11057.281276716987;11059.72350691231;10724.088452481548;10812.459377031195;10829.133669306437;10840.728981775519;10849.132069434427;44702.55297447016;45082.098358032985;45175.548244517464;45190;45201.77399113016;3266.6896644818703;3278.246003419203;3624.5288112545463;4124.938125928114;4152.937705934413;33740.49389259139;33873.32253354919;34237.82881085579;34297.657023429696;34473.482897756556;3949.822257998397;3958.1094253792953;4007.198560287925;4014.5182578090526;4033.657139143176;315155;319439;320276;320834;321378;61289.38710612926;62220.37779622146;68944;69319.65340173368;69328.65335673289;75019;76067.61966190134;76417.61791191119;76520.23479765242;76522.61738691271;17871;18156.909215453838;18167.818321816878;18184.090795460175;18308.176132073822;13756.587302380945;13795.241261730702;13796.517121900686;13819.792703109462;13837.377318020714;22601.547969040857;23016.539669206522;23087.653685194735;23190.65214021791;23241.651375229387;8640.481571105754;8844.690435834715;8867.334949878785;8875.201231889161;9059.547022648841;968.5109019945023;1052.894710528941;1105.5522513382093;1107.634480621393;1158.461315488302;30398.54402183969;30410.847945760128;30575.237299681085;31327.843360783507;31768.682313176574;1106.5684383090602;1135.6706555099095;1156.8091264941359;1201.9900497512438;1241.0567968343944;13656.726865462833;15897.364105435652;16012.759808602881;16177.676446471005;16468.752968705478;375119;375196;376947;378182;380517;59217.703911481025;60127.699361503786;60292.397076029556;60402.69798650979;60683;1463.9752173478607;1464.421553486367;1480.4670318685264;1497.5731916403772;1504.9398024078914;31174.688253118085;31202.375952481278;31530.211744706565;31636.36727265488;31652.841735791175;52713.7364313176;52832;53056.734716327715;53108.46891531035;53153.46846531485;350458;351707;352562;353083;353863;31494.055178344686;32095.35809283848;37059.444108337855;47661.52338476571;47723.5227647733;51241.23138153005;52206.47793522168;53173.734131329096;53900.46099539111;53991.46008539864;42614;42933.35599966066;46743.29885051795;46895.531044690484;47924.25127721018;42941.71174864759;43449.782751086044;43472.56527434812;43608.78195609002;44088.338674920546;43351;46858.531414685414;47325.290120648904;47794.52205477901;48292.27561586649;36821.81589092038;37897;37911.81044094762;38986.80506597449;39154.4126838092;29535.55696664595;29636.407271854012;29638;29764.85117574398;29859.70140298569 23:40:12 INFO - 2015-12-15 23:40:12,896 DEBUG : BROWSER_OUTPUT: |2;jquery.html;395.5332707405264;398.6392314954967;398.84245722939437;401.16557560274634;402.2417742555284;18464.723029154557;18559.90720046401;18899.905500472494;20411.897940510313;20446.38660840177;18305.450836474894;18794.34219802307;19063.142158602863;19074.904625476887;19396.22415103394;5762.711864406784;5788.739506722208;5868.530517558585;5875.735591898375;5883.146943693162;5904.321003084658;5934.910976335358;5937.079752638348;5937.406259374071;5938;4364.388985542027;4475.060237350144;5880;5899.675517846516;6254.968725156368;6357.17356743625;6363.172787537591;6404.42360187583;6410.1987251593555;6415.358464153571;5593.077142271521;5684.8010319639025;5687;5687.40282270364;5692.260006199168;22632.886835565718;22949.88525057364;23542.411439713975;23618.409539761473;23673.526529469487;2196.5042530595188;2294.724633044029;2304.5390921815538;2374.192774456693;2377.4413012941945;10673.519691613896;10970.835437468444;11212.383318917457;14805.259737013106;15036.774448383283;1847.7690288713911;1849.3434830635065;1850.1766713812358;1857.721341798741;1873.175802646831;67299.3270067293;67662.61686915635;68743;69285.30714692839;69571.65214173897;7983.241592048862;8009.879851802228;8023.6790528378215;8084.151164127682;8086.070101938235;9643.469609171563;9653.662121825702;9667.27495437845;9671.613135474643;9680.612775489042;6409.391107844747;6457.709403076873;6500.642464664489;6502.187226596676;6508.837279068061;6637.966810165918;6718.294579069225;6737.393634572912;6769.424598909148;6782.389584937379;3668.532586965235;3690.0590349460717;3698.4082546792383;3721.7208709346905;3723.3298006358573;1261.2243470265903;1269.6444995401303;1270.3457719274581;1276.5978716704208;1322.1868550841164;433.7787728258548;478.17276112325874;488.7756170792214;501.92471129330164;511.40166005773335;11557.653270401901;12708.618741437771;12733;13005.024623153113;13888.4444622216;530.8911673107003;541.0153520592484;546.4016901492859;551.2998491915246;557.7936163619487;5769.307683077974;7403.407727381797;7523.924760752432;7531.397488200931;7628.80927976805;603049;605197;620345;624253;624617;66283;84160.57919710362;84246.15753842382;88942.55528722444;89232;588.8468998060536;606.130203158469;607.2682417686677;608.6804427675418;608.6986941464004;11946.761064778482;12166.695832604255;12399.566015189424;12406.689832754253;12471.189372690746;38480.23039539137;38573.4213986796;38957;40166;40219.798901005306;52293.7385313071;53330.46669533255;53391.199132013826;54146.45853541414;54272.728636356565;7468.925310746823;7529.002608371631;7643.350315223268;7732.458727889219;7739.458237923292;13090.083694141318;13518.729625407634;13532.255725935169;13698.794518082037;13757.518486852912;11683.240589361665;11871.584494542649;12051.156419050581;12100.636980890587;12173.756524869275;11115;11863.822042669195;11872.881271187523;11917.76164476723;12007.579734709243;11917.880821192024;12006.519739210335;12056.276623402624;12181.939090304491;12183;7748;7789.805254868447;7857.2928436441;7865.292123708894;7876.881846772418;12188.085893557842;12188.878111218773;12221.572244971383;12579.87420125787;12629 23:40:12 INFO - 2015-12-15 23:40:12,900 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1477.9926100369496;1528.2740698168368;1645.835416458355;1737.739339099135;1897.3454158315376;7216.567005979645;7330.413566914647;8002.719904803325;8467.91532084679;8474.533900635462;7991.520508769478;8179.91820081799;8266.214709602593;8268.503889766618;8271.83456330873;2388.7850093491625;2415.915442959497;2416.178499310234;2417.492326611405;2417.6736140620965;2154.1814110637933;2195.2865318771383;2370.95258094838;2414.1188466209796;2465.1125594785863;2405.0259644843777;2407.16952651335;2410.8915098820594;2412.0472413396697;2413.2760171948435;2437.7805997460223;2462.753724627541;2464.0636558107894;2464.18681834994;2466.3464181991785;2400.7359190489033;2441.4872876695736;2443.0716327795326;2444.1201167579657;2444.877756112205;8339.332853371716;8359.540225287607;8378.078411374805;8396.874046889301;8397.874031889527;5025.472325405853;5089.643724939256;5100.719460429675;5101.668391554575;5103.974480127576;3322.318924620447;3363.260082781784;3392.5928888533285;3421.401254780428;3433.4163192257374;1128.086250137383;1132.2470557079532;1133.4502766158407;1163.2555164694602;1185.5317149725897;11511.827322590168;11517.424128793611;11531.250468719507;11553.595624153115;11556.306621602731;4762.78567464465;4764.547368000104;4784.832530861473;4810.39870016248;4823.348847905488;9745.951270243746;9750.366226195274;9766.121049105615;9778.119969202806;9800.803983920281;5182.533571978464;5186.611004174702;5195.19474481456;5195.610329225323;5208.010478009166;4719.551642593948;4729.834455794031;4736.171170045297;4744.264638980963;4749.4063242094735;3519.1026288296835;3924.843006279774;3994.360902255625;4007.1785284016705;4036.466142865751;1234.7283597608512;1269.8476182858117;1294.1911305434103;1295.2487557216791;1298.1626850681257;1198.856137263534;1443.5092068696692;1476.7415702252063;1478.2682572126866;1481.274175653922;7841.529508229411;7880.763577092696;7959.602019899097;7971.322437592754;7980.3615710743;1149.3219000789575;1165.3299352872132;1185.3125187391292;1212.3029258176584;1214.332117335462;6335.334789847092;6363.5863668861375;6416.390442907917;6425.742970281136;6429.421352078241;9725.0761177688;9911.355761875455;9915.553800079013;9938.503074846229;9964.601415943425;9746.122848943629;9911.355761875455;9938.751531211632;9951.651692190884;10009.749756256006;7739.535627862347;7741.148473667892;7789.766307010798;7793.610319484003;7804.453688241882;7586;7633.503822251536;7649.847003059796;7650.2349765023055;7669.501482403626;8920.063393343866;9090.500022498825;9108.726738197865;9151.816963660822;9154.908450915673;8975.371723979259;9124.406913550598;9230.076992300716;9270.675526356545;9281.39670921388;7541.7360392386;7611.200823913631;7617.390608751176;7617.466777325534;7618.428617853794;7364.521306115085;7562.810929726801;7581.090269167735;7876.133625301327;8186.590670466334;7509.8498030038;7631.923680763121;7670.69317227327;7745.845083098419;7793.64928578204;7381.9630901847295;7432.739854105081;7534.133574638987;7570.470067095251;7660.616969151632;7589.848203036019;7595.050618672666;7651.043619547557;7655.081390232985;7714.842773584007;7048.506604537633;7092.6099064551945;7095.46783991213;7101.786946391617;7111.040009598638;7080.398166155935;7091.936209568606;7209.6034718091005;7243.0946131733535;7277.672504737194 23:40:12 INFO - 2015-12-15 23:40:12,900 DEBUG : BROWSER_OUTPUT: |4;prototype.html;361.32432351502695;363.4530032301386;372.6720485972344;376.1517777411935;385.87651951375574;5912.763489460416;5954.612950158236;6059.818205453832;6096.1160631708435;6101.115338275948;5852.882942341151;5921.171036054956;5935.436133567304;5950;5978.970105149479;4661.254199328108;4680.836170734016;4698.107359601682;4703.388559487262;4703.929441058387;4533.184026875161;4590.724556526602;4658.743769092698;4697.78859951303;4707.670463067587;4661.114388266218;4678.461976872669;4693.624510039189;4695.90608187836;4702.177119004177;4663.463701674317;4672.065586882603;4675.836345727883;4676.97661511692;4684.2973553967;4169.207850508394;4173.5409104998425;4176.018635620627;4178;4180.7909604519955;5574.86062848432;5678.829635110953;5680.488756011979;5685.800996965087;5778.508826749731;5506.366767821712;5506.394296627368;5507.228987941691;5510.504054635102;5510.586705997026;6349.809505714836;6357.5867568608355;6370.90443643341;6374.330695276975;6383.968080159616;4577.450705915278;4645.581897629229;4655.953440465586;4657.161710892038;4662.696924699883;6588.439982601484;6620.900686489658;6621.966890165567;6628.403443690091;6634.767783127568;4793.18515852306;4794.640401969797;4797.328374027639;4799.616030717534;4800.231962885921;4839.491853355417;4841.104395686809;4845.878853028703;4850.830220942321;4851.175300198904;5361.169018802092;5473.370562385297;5474.096774032319;5475.342958844964;5484.478974497416;6355.269144048401;6381.3937675920715;6390.8721825563225;6402.007688808242;6403.359664033559;1725.1632958015352;1726.7841519810024;1727.0760143323514;1727.6803791298398;1728.2568495546784;1179.168686076314;1191.0709646475764;1192.898603618702;1211.927284362941;1227.110344999868;6178.320384757673;6247.656378899113;6248;6250.562460627803;6251;6386.07401926718;6454.612723236621;6551.050097735897;6560.212774467094;6587.637679927554;6144.109104179869;6156.6921653917125;6174.567780255339;6178.042403427476;6183.876322473526;3587.408077667156;3632.164602141522;3640.399334109842;3642.21692336149;3647.85408583659;6543.214814222324;6623.701933412912;6681.632510211888;6687.130673012574;6720.8319792005595;6657.633830139292;6686.732530698815;6692.832679182962;6768.627725475146;6800.6939687714175;6799.456043516507;6803.7618683345845;6819.965900170468;6822.624755638488;6827.2490026098085;6474.223093228843;6570.8028759137305;6628.53600247978;6637.303083176197;6637.601743895381;6376.808695739135;6424.486041116793;6447.129637498878;6457.418832305115;6463.418292353711;6718.22740384862;6731.124953755981;6760.18877734675;6768.695408706521;6769.6460707859615;4541.434377763829;4543.5910768031035;4582.862514124582;4601.28680054592;4607.723536587815;6663;6670.032845237511;6675.599464032173;6678.866422671616;6680.398764111059;6452.064450654722;6478.481721462366;6486.4486518646445;6489.156409666713;6492.870142597028;6373.840653983688;6479.287278399466;6483.319251478715;6492.188476440445;6495.415412612888;6373.075903993988;6376.649284289409;6386.648734319471;6387.872242555216;6388.361163883575;6466.450351719968;6472.773452929125;6494.447971922439;6496.967515162394;6498.740050397932;6058.606190597597;6151.96924015377;6289.213848268966;6294.181756371642;6306.0225665022 23:40:12 INFO - 2015-12-15 23:40:12,904 DEBUG : BROWSER_OUTPUT: |5;yui.html;944.3012170993467;951.7620594851287;956.2015716876409;965.329096278087;965.7730433348162;14329.06861054032;14690.412383504647;14716.117032978002;14731.48439804607;14739.336729847157;15621.140837253946;15879.285432155551;15884.444044458447;15898;15920.203989800522;765.8085478630343;768.5888049893308;914.0448231597996;915.0300681277866;925.319889880937;908.8091500784843;913.7121806630923;917.4403613795613;918.5177781664646;919.1084647891535;951.2532661860441;955.9091886270793;957.0333962697656;957.2437774158441;958.6692591056075;944.527736131934;947.7393716727886;947.9289053320994;948.1845612773044;948.4451595816456;696.9442444604471;697.2783169419627;701.0745815523511;701.3512500936613;702.0452185028357;15040.247987600576;15079.773803392958;15091.24543772818;15102.622434439118;15136;11641.301521908712;11667.416629168592;11759.17685761997;11815.468303926344;12005.93997030018;10553.630622928238;10789.514471848786;10822;11052.336859788358;11181.161412894066;3434.10713214566;3443.500692399588;3450;3485.1809824691177;3538.6461353864665;49452.50547494551;49617.75191124057;49840.501594984315;50136.247956280684;50565.24152137721;8581.570921454027;8659.220670139717;8660.13398660129;8690;8718.65125394977;14210.289485525682;14224.14655120696;14258.215798130996;14265.64335891611;14278.357473913698;7293.380062694624;7370.078740157481;7410.147832999166;7471.701131954661;7605.049368828896;4541.114482675913;4549.863504094882;4573.291139873325;4623.86128416148;4647.767611619473;2956.6452025756616;2973.3755911258877;2978.5532170174647;2987.462256793798;2996.865141068657;450.1020464173994;451.62740738890545;456.6757602102478;462.20270034191424;464.21547584582623;541.94591519495;666.5700623098171;671.8589096289736;693.9410150137194;700.733721185946;10105.595776169019;10218.591256349811;10471.47642617866;10526.789464210673;10546.472676366151;719.7552832037132;723.6888138100562;737.5169264131895;748.5620911766568;753.574230559743;4607.723536587815;4741.762911854394;4819.855404337875;4830.212821256157;5011.6241281904;63724.681376592824;63879.041814372824;63970;65722.34277657258;65994.67002664956;57269.14096288559;57528.42471575314;57698;59128.704356477945;59879.10181347283;54478.09326673547;54733.72633136809;54761.45238547563;55573.44426555683;56137.157942630896;9927.255455840696;9941.602335906482;10002.649907253211;10123.79752404933;10252.743681408025;40529.392059118545;40576.1884762309;40643;40914.590854091075;41523.58476415197;47591.286130707376;49652.75173624109;50305.24542131944;50464.495355047446;50583.241251381995;9170.312226582899;9254.768630784285;9264.583093760662;9306.906930930874;9406.576704048472;13826.032177747462;14102.858971410566;14221.71556568842;14273.072250303698;14273.928630356782;13293.534726284533;13485.39315730814;13652.24912629815;14097.788533171806;14464.349104290122;13342.86657133455;13356.866431335562;13372;13530.458781648624;13733.244671542761;11450.312981221154;12489.31308778026;12911;13482;13808.585742427744;10929.78140437171;11039;11081.445927703744;11123.72190695239;11312;9222.440211587493;10702.411367374558;10777.89222107769;10876.23866329381;10995.285306455055 23:40:12 INFO - 2015-12-15 23:40:12,904 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:40:12 INFO - 2015-12-15 23:40:12,904 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:40:12 INFO - 2015-12-15 23:40:12,904 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,695 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: __startTimestamp1450251612891__endTimestamp 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: Number of tests: 6 23:40:12 INFO - 2015-12-15 23:40:12,905 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5709.64 Median:5913.23 stddev:643.57 (10.9%) stddev-sans-first:617.24 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: Values: 3303.6 3406.5 3439.3 3444.5 3462.9 5740.0 5740.2 5748.8 5749.2 5758.1 5756.7 5773.7 5790.6 5798.4 5821.5 5090.5 5242.1 5263.1 5284.8 5322.1 5168.8 5169.8 5175.8 5181.0 5185.2 5134.2 5197.9 5225.8 5240.1 5249.3 5177.5 5325.4 5326.4 5327.2 5328.4 5233.6 5277.7 5279.1 5295.4 5296.6 5310.9 5438.2 5473.9 5600.2 5607.0 6182.4 6212.6 6213.0 6214.1 6223.5 6229.7 6243.6 6244.9 6246.6 6254.5 5553.1 5681.6 5693.0 5806.9 5813.0 6277.0 6305.1 6361.0 6363.7 6371.2 6098.8 6133.8 6141.8 6146.7 6173.0 6003.8 6085.6 6115.4 6203.9 6222.1 6169.7 6170.9 6172.3 6176.1 6199.2 5906.3 6146.0 6159.3 6160.2 6164.2 4593.9 4644.0 4660.9 4668.9 4688.4 4173.5 4179.4 4192.0 4207.9 4217.4 5641.6 5731.4 5812.8 5817.9 5818.2 6083.9 6136.8 6150.0 6171.4 6178.1 5840.4 5884.0 5886.1 5892.2 5904.1 5236.4 5317.6 5360.0 5361.3 5364.2 6368.2 6371.7 6373.4 6378.3 6380.2 6205.3 6209.5 6209.9 6213.3 6217.2 6138.9 6143.7 6263.1 6342.1 6372.1 5989.5 6005.5 6007.2 6032.2 6065.6 6032.5 6049.8 6052.0 6058.0 6101.9 5818.7 5963.3 5966.8 6138.4 6143.8 6068.7 6069.1 6074.0 6086.5 6095.8 5697.4 5922.5 5965.5 5977.4 5978.6 5913.2 6011.2 6016.2 6034.9 6057.8 5835.5 5839.8 5842.5 5852.2 5923.6 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:55000.64 Median:36940.63 stddev:91628.98 (248.0%) stddev-sans-first:91795.10 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: Values: 635.3 635.6 640.8 652.6 657.7 44306.3 45243.8 45247.5 45259.3 45318.1 48377.3 48938.5 48975.3 48996.5 49080.8 8223.9 8330.5 9253.9 9453.1 10654.9 10808.6 10948.8 10953.1 10960.2 10960.5 10979.5 11077.6 11090.6 11091.7 11098.8 10946.5 11047.9 11055.8 11057.3 11059.7 10724.1 10812.5 10829.1 10840.7 10849.1 44702.6 45082.1 45175.5 45190.0 45201.8 3266.7 3278.2 3624.5 4124.9 4152.9 33740.5 33873.3 34237.8 34297.7 34473.5 3949.8 3958.1 4007.2 4014.5 4033.7 315155.0 319439.0 320276.0 320834.0 321378.0 61289.4 62220.4 68944.0 69319.7 69328.7 75019.0 76067.6 76417.6 76520.2 76522.6 17871.0 18156.9 18167.8 18184.1 18308.2 13756.6 13795.2 13796.5 13819.8 13837.4 22601.5 23016.5 23087.7 23190.7 23241.7 8640.5 8844.7 8867.3 8875.2 9059.5 968.5 1052.9 1105.6 1107.6 1158.5 30398.5 30410.8 30575.2 31327.8 31768.7 1106.6 1135.7 1156.8 1202.0 1241.1 13656.7 15897.4 16012.8 16177.7 16468.8 375119.0 375196.0 376947.0 378182.0 380517.0 59217.7 60127.7 60292.4 60402.7 60683.0 1464.0 1464.4 1480.5 1497.6 1504.9 31174.7 31202.4 31530.2 31636.4 31652.8 52713.7 52832.0 53056.7 53108.5 53153.5 350458.0 351707.0 352562.0 353083.0 353863.0 31494.1 32095.4 37059.4 47661.5 47723.5 51241.2 52206.5 53173.7 53900.5 53991.5 42614.0 42933.4 46743.3 46895.5 47924.3 42941.7 43449.8 43472.6 43608.8 44088.3 43351.0 46858.5 47325.3 47794.5 48292.3 36821.8 37897.0 37911.8 38986.8 39154.4 29535.6 29636.4 29638.0 29764.9 29859.7 23:40:12 INFO - 2015-12-15 23:40:12,906 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,907 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30947.01 Median:506.66 stddev:100773.40 (19889.6%) stddev-sans-first:101029.98 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: Values: 395.5 398.6 398.8 401.2 402.2 18464.7 18559.9 18899.9 20411.9 20446.4 18305.5 18794.3 19063.1 19074.9 19396.2 5762.7 5788.7 5868.5 5875.7 5883.1 5904.3 5934.9 5937.1 5937.4 5938.0 4364.4 4475.1 5880.0 5899.7 6255.0 6357.2 6363.2 6404.4 6410.2 6415.4 5593.1 5684.8 5687.0 5687.4 5692.3 22632.9 22949.9 23542.4 23618.4 23673.5 2196.5 2294.7 2304.5 2374.2 2377.4 10673.5 10970.8 11212.4 14805.3 15036.8 1847.8 1849.3 1850.2 1857.7 1873.2 67299.3 67662.6 68743.0 69285.3 69571.7 7983.2 8009.9 8023.7 8084.2 8086.1 9643.5 9653.7 9667.3 9671.6 9680.6 6409.4 6457.7 6500.6 6502.2 6508.8 6638.0 6718.3 6737.4 6769.4 6782.4 3668.5 3690.1 3698.4 3721.7 3723.3 1261.2 1269.6 1270.3 1276.6 1322.2 433.8 478.2 488.8 501.9 511.4 11557.7 12708.6 12733.0 13005.0 13888.4 530.9 541.0 546.4 551.3 557.8 5769.3 7403.4 7523.9 7531.4 7628.8 603049.0 605197.0 620345.0 624253.0 624617.0 66283.0 84160.6 84246.2 88942.6 89232.0 588.8 606.1 607.3 608.7 608.7 11946.8 12166.7 12399.6 12406.7 12471.2 38480.2 38573.4 38957.0 40166.0 40219.8 52293.7 53330.5 53391.2 54146.5 54272.7 7468.9 7529.0 7643.4 7732.5 7739.5 13090.1 13518.7 13532.3 13698.8 13757.5 11683.2 11871.6 12051.2 12100.6 12173.8 11115.0 11863.8 11872.9 11917.8 12007.6 11917.9 12006.5 12056.3 12181.9 12183.0 7748.0 7789.8 7857.3 7865.3 7876.9 12188.1 12188.9 12221.6 12579.9 12629.0 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5882.96 Median:6738.96 stddev:3007.48 (44.6%) stddev-sans-first:2997.69 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: Values: 1478.0 1528.3 1645.8 1737.7 1897.3 7216.6 7330.4 8002.7 8467.9 8474.5 7991.5 8179.9 8266.2 8268.5 8271.8 2388.8 2415.9 2416.2 2417.5 2417.7 2154.2 2195.3 2371.0 2414.1 2465.1 2405.0 2407.2 2410.9 2412.0 2413.3 2437.8 2462.8 2464.1 2464.2 2466.3 2400.7 2441.5 2443.1 2444.1 2444.9 8339.3 8359.5 8378.1 8396.9 8397.9 5025.5 5089.6 5100.7 5101.7 5104.0 3322.3 3363.3 3392.6 3421.4 3433.4 1128.1 1132.2 1133.5 1163.3 1185.5 11511.8 11517.4 11531.3 11553.6 11556.3 4762.8 4764.5 4784.8 4810.4 4823.3 9746.0 9750.4 9766.1 9778.1 9800.8 5182.5 5186.6 5195.2 5195.6 5208.0 4719.6 4729.8 4736.2 4744.3 4749.4 3519.1 3924.8 3994.4 4007.2 4036.5 1234.7 1269.8 1294.2 1295.2 1298.2 1198.9 1443.5 1476.7 1478.3 1481.3 7841.5 7880.8 7959.6 7971.3 7980.4 1149.3 1165.3 1185.3 1212.3 1214.3 6335.3 6363.6 6416.4 6425.7 6429.4 9725.1 9911.4 9915.6 9938.5 9964.6 9746.1 9911.4 9938.8 9951.7 10009.7 7739.5 7741.1 7789.8 7793.6 7804.5 7586.0 7633.5 7649.8 7650.2 7669.5 8920.1 9090.5 9108.7 9151.8 9154.9 8975.4 9124.4 9230.1 9270.7 9281.4 7541.7 7611.2 7617.4 7617.5 7618.4 7364.5 7562.8 7581.1 7876.1 8186.6 7509.8 7631.9 7670.7 7745.8 7793.6 7382.0 7432.7 7534.1 7570.5 7660.6 7589.8 7595.1 7651.0 7655.1 7714.8 7048.5 7092.6 7095.5 7101.8 7111.0 7080.4 7091.9 7209.6 7243.1 7277.7 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5394.31 Median:6122.61 stddev:1569.36 (25.6%) stddev-sans-first:1527.62 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: Values: 361.3 363.5 372.7 376.2 385.9 5912.8 5954.6 6059.8 6096.1 6101.1 5852.9 5921.2 5935.4 5950.0 5979.0 4661.3 4680.8 4698.1 4703.4 4703.9 4533.2 4590.7 4658.7 4697.8 4707.7 4661.1 4678.5 4693.6 4695.9 4702.2 4663.5 4672.1 4675.8 4677.0 4684.3 4169.2 4173.5 4176.0 4178.0 4180.8 5574.9 5678.8 5680.5 5685.8 5778.5 5506.4 5506.4 5507.2 5510.5 5510.6 6349.8 6357.6 6370.9 6374.3 6384.0 4577.5 4645.6 4656.0 4657.2 4662.7 6588.4 6620.9 6622.0 6628.4 6634.8 4793.2 4794.6 4797.3 4799.6 4800.2 4839.5 4841.1 4845.9 4850.8 4851.2 5361.2 5473.4 5474.1 5475.3 5484.5 6355.3 6381.4 6390.9 6402.0 6403.4 1725.2 1726.8 1727.1 1727.7 1728.3 1179.2 1191.1 1192.9 1211.9 1227.1 6178.3 6247.7 6248.0 6250.6 6251.0 6386.1 6454.6 6551.1 6560.2 6587.6 6144.1 6156.7 6174.6 6178.0 6183.9 3587.4 3632.2 3640.4 3642.2 3647.9 6543.2 6623.7 6681.6 6687.1 6720.8 6657.6 6686.7 6692.8 6768.6 6800.7 6799.5 6803.8 6820.0 6822.6 6827.2 6474.2 6570.8 6628.5 6637.3 6637.6 6376.8 6424.5 6447.1 6457.4 6463.4 6718.2 6731.1 6760.2 6768.7 6769.6 4541.4 4543.6 4582.9 4601.3 4607.7 6663.0 6670.0 6675.6 6678.9 6680.4 6452.1 6478.5 6486.4 6489.2 6492.9 6373.8 6479.3 6483.3 6492.2 6495.4 6373.1 6376.6 6386.6 6387.9 6388.4 6466.5 6472.8 6494.4 6497.0 6498.7 6058.6 6152.0 6289.2 6294.2 6306.0 23:40:12 INFO - 2015-12-15 23:40:12,908 DEBUG : BROWSER_OUTPUT: 23:40:12 INFO - 2015-12-15 23:40:12,910 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15158.70 Median:2544.04 stddev:18003.61 (707.7%) stddev-sans-first:18022.47 23:40:12 INFO - 2015-12-15 23:40:12,910 DEBUG : BROWSER_OUTPUT: Values: 944.3 951.8 956.2 965.3 965.8 14329.1 14690.4 14716.1 14731.5 14739.3 15621.1 15879.3 15884.4 15898.0 15920.2 765.8 768.6 914.0 915.0 925.3 908.8 913.7 917.4 918.5 919.1 951.3 955.9 957.0 957.2 958.7 944.5 947.7 947.9 948.2 948.4 696.9 697.3 701.1 701.4 702.0 15040.2 15079.8 15091.2 15102.6 15136.0 11641.3 11667.4 11759.2 11815.5 12005.9 10553.6 10789.5 10822.0 11052.3 11181.2 3434.1 3443.5 3450.0 3485.2 3538.6 49452.5 49617.8 49840.5 50136.2 50565.2 8581.6 8659.2 8660.1 8690.0 8718.7 14210.3 14224.1 14258.2 14265.6 14278.4 7293.4 7370.1 7410.1 7471.7 7605.0 4541.1 4549.9 4573.3 4623.9 4647.8 2956.6 2973.4 2978.6 2987.5 2996.9 450.1 451.6 456.7 462.2 464.2 541.9 666.6 671.9 693.9 700.7 10105.6 10218.6 10471.5 10526.8 10546.5 719.8 723.7 737.5 748.6 753.6 4607.7 4741.8 4819.9 4830.2 5011.6 63724.7 63879.0 63970.0 65722.3 65994.7 57269.1 57528.4 57698.0 59128.7 59879.1 54478.1 54733.7 54761.5 55573.4 56137.2 9927.3 9941.6 10002.6 10123.8 10252.7 40529.4 40576.2 40643.0 40914.6 41523.6 47591.3 49652.8 50305.2 50464.5 50583.2 9170.3 9254.8 9264.6 9306.9 9406.6 13826.0 14102.9 14221.7 14273.1 14273.9 13293.5 13485.4 13652.2 14097.8 14464.3 13342.9 13356.9 13372.0 13530.5 13733.2 11450.3 12489.3 12911.0 13482.0 13808.6 10929.8 11039.0 11081.4 11123.7 11312.0 9222.4 10702.4 10777.9 10876.2 10995.3 23:40:12 INFO - 2015-12-15 23:40:12,910 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:40:12 INFO - 2015-12-15 23:40:12,910 DEBUG : BROWSER_OUTPUT: 23:40:13 INFO - 2015-12-15 23:40:13,405 INFO : Browser exited with error code: 0 23:40:13 INFO - 2015-12-15 23:40:13,428 INFO : Completed test dromaeo_css (00:18:29) 23:40:13 INFO - 2015-12-15 23:40:13,428 INFO : Starting test kraken 23:40:13 INFO - 2015-12-15 23:40:13,428 DEBUG : operating with platform_type : linux_ 23:40:13 INFO - 2015-12-15 23:40:13,429 INFO : Initialising browser for kraken test... 23:40:13 INFO - 2015-12-15 23:40:13,435 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCBWZJu/profile http://localhost:44289/getInfo.html 23:40:16 INFO - 2015-12-15 23:40:16,696 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:40:16 INFO - 2015-12-15 23:40:16,696 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:40:16 INFO - 2015-12-15 23:40:16,696 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 23:40:16 INFO - 2015-12-15 23:40:16,696 DEBUG : BROWSER_OUTPUT: __metrics 23:40:16 INFO - 2015-12-15 23:40:16,833 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] 23:40:17 INFO - 2015-12-15 23:40:17,804 INFO : Browser initialized. 23:40:17 INFO - 2015-12-15 23:40:17,805 INFO : Running cycle 1/1 for kraken test... 23:40:17 INFO - 2015-12-15 23:40:17,805 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCBWZJu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:40:29 INFO - 2015-12-15 23:40:29,500 DEBUG : BROWSER_OUTPUT: RSS: Main: 131821568 23:40:29 INFO - 2015-12-15 23:40:29,501 DEBUG : BROWSER_OUTPUT: 23:40:31 INFO - 2015-12-15 23:40:31,936 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44289/tests/kraken/driver.html?testName=audio-beat-detection) 23:40:32 INFO - 2015-12-15 23:40:32,502 DEBUG : BROWSER_OUTPUT: RSS: Main: 166526976 23:40:32 INFO - 2015-12-15 23:40:32,503 DEBUG : BROWSER_OUTPUT: 23:40:35 INFO - 2015-12-15 23:40:35,047 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44289/tests/kraken/driver.html?testName=audio-dft) 23:40:35 INFO - 2015-12-15 23:40:35,612 DEBUG : BROWSER_OUTPUT: RSS: Main: 161538048 23:40:35 INFO - 2015-12-15 23:40:35,612 DEBUG : BROWSER_OUTPUT: 23:40:38 INFO - 2015-12-15 23:40:38,395 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44289/tests/kraken/driver.html?testName=audio-fft) 23:40:38 INFO - 2015-12-15 23:40:38,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 159768576 23:40:38 INFO - 2015-12-15 23:40:38,958 DEBUG : BROWSER_OUTPUT: 23:40:40 INFO - 2015-12-15 23:40:40,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44289/tests/kraken/driver.html?testName=audio-oscillator) 23:40:41 INFO - 2015-12-15 23:40:41,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 159084544 23:40:41 INFO - 2015-12-15 23:40:41,456 DEBUG : BROWSER_OUTPUT: 23:40:43 INFO - 2015-12-15 23:40:43,590 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44289/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:40:44 INFO - 2015-12-15 23:40:44,144 DEBUG : BROWSER_OUTPUT: RSS: Main: 157093888 23:40:44 INFO - 2015-12-15 23:40:44,144 DEBUG : BROWSER_OUTPUT: 23:40:47 INFO - 2015-12-15 23:40:47,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44289/tests/kraken/driver.html?testName=imaging-darkroom) 23:40:47 INFO - 2015-12-15 23:40:47,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 157519872 23:40:47 INFO - 2015-12-15 23:40:47,590 DEBUG : BROWSER_OUTPUT: 23:40:50 INFO - 2015-12-15 23:40:50,939 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44289/tests/kraken/driver.html?testName=imaging-desaturate) 23:40:51 INFO - 2015-12-15 23:40:51,504 DEBUG : BROWSER_OUTPUT: RSS: Main: 157917184 23:40:51 INFO - 2015-12-15 23:40:51,505 DEBUG : BROWSER_OUTPUT: 23:40:54 INFO - 2015-12-15 23:40:54,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44289/tests/kraken/driver.html?testName=json-parse-financial) 23:40:54 INFO - 2015-12-15 23:40:54,798 DEBUG : BROWSER_OUTPUT: RSS: Main: 157282304 23:40:54 INFO - 2015-12-15 23:40:54,798 DEBUG : BROWSER_OUTPUT: 23:40:56 INFO - 2015-12-15 23:40:56,368 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44289/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:40:56 INFO - 2015-12-15 23:40:56,917 DEBUG : BROWSER_OUTPUT: RSS: Main: 163536896 23:40:56 INFO - 2015-12-15 23:40:56,917 DEBUG : BROWSER_OUTPUT: 23:40:58 INFO - 2015-12-15 23:40:58,612 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:40:59 INFO - 2015-12-15 23:40:59,219 DEBUG : BROWSER_OUTPUT: RSS: Main: 162861056 23:40:59 INFO - 2015-12-15 23:40:59,219 DEBUG : BROWSER_OUTPUT: 23:41:01 INFO - 2015-12-15 23:41:01,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:41:01 INFO - 2015-12-15 23:41:01,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 167407616 23:41:01 INFO - 2015-12-15 23:41:01,738 DEBUG : BROWSER_OUTPUT: 23:41:04 INFO - 2015-12-15 23:41:04,052 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:41:04 INFO - 2015-12-15 23:41:04,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 171540480 23:41:04 INFO - 2015-12-15 23:41:04,626 DEBUG : BROWSER_OUTPUT: 23:41:07 INFO - 2015-12-15 23:41:07,075 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:41:07 INFO - 2015-12-15 23:41:07,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 170221568 23:41:07 INFO - 2015-12-15 23:41:07,644 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44289/tests/kraken/driver.html?testName=ai-astar) 23:41:09 INFO - 2015-12-15 23:41:09,504 DEBUG : BROWSER_OUTPUT: RSS: Main: 229703680 23:41:09 INFO - 2015-12-15 23:41:09,504 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,507 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;104;109;111;111;103;111;107;111 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;153;121;144;159;125;143;150;122;168;129 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;160;164;162;161;161;162;162;162 23:41:09 INFO - 2015-12-15 23:41:09,508 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;74;70;145;71;71;71;71;152;70 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;89;143;89;90;89;139;88;89;129 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;110;109;109;110;110;109;110 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;145;146;145;145;145;145;145 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;55;57;57;58;57;55;57;58;54 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;63;62;62;63;63;63;63;63;62 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;75;79;79;77;78;77;77;78;78 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;111;117;108;109;119;122;118;110;122;110 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;140;141;140;138;140;140;138;139;138 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;63;66;62;63;61;62;62;62;62 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:41:09 INFO - 2015-12-15 23:41:09,509 DEBUG : BROWSER_OUTPUT: __start_cc_report 23:41:09 INFO - 2015-12-15 23:41:09,510 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,874 23:41:09 INFO - 2015-12-15 23:41:09,510 DEBUG : BROWSER_OUTPUT: __end_cc_report 23:41:09 INFO - 2015-12-15 23:41:09,510 DEBUG : BROWSER_OUTPUT: __startTimestamp1450251669506__endTimestamp 23:41:09 INFO - 2015-12-15 23:41:09,510 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: Number of tests: 14 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.70 Median:111.00 stddev:3.06 (2.8%) stddev-sans-first:3.24 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 104.0 109.0 111.0 111.0 103.0 111.0 107.0 111.0 23:41:09 INFO - 2015-12-15 23:41:09,511 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.40 Median:147.00 stddev:16.50 (11.2%) stddev-sans-first:16.96 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: Values: 153.0 121.0 144.0 159.0 125.0 143.0 150.0 122.0 168.0 129.0 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 160.0 164.0 162.0 161.0 161.0 162.0 162.0 162.0 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.60 Median:71.00 stddev:32.68 (46.0%) stddev-sans-first:34.18 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: Values: 71.0 74.0 70.0 145.0 71.0 71.0 71.0 71.0 152.0 70.0 23:41:09 INFO - 2015-12-15 23:41:09,512 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.50 Median:89.00 stddev:23.37 (26.3%) stddev-sans-first:24.27 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: Values: 90.0 89.0 143.0 89.0 90.0 89.0 139.0 88.0 89.0 129.0 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 110.0 109.0 109.0 110.0 110.0 109.0 110.0 23:41:09 INFO - 2015-12-15 23:41:09,513 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,514 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 23:41:09 INFO - 2015-12-15 23:41:09,514 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 23:41:09 INFO - 2015-12-15 23:41:09,514 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,514 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:41:09 INFO - 2015-12-15 23:41:09,514 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.35 (2.4%) stddev-sans-first:1.42 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: Values: 56.0 55.0 57.0 57.0 58.0 57.0 55.0 57.0 58.0 54.0 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 62.0 62.0 63.0 63.0 63.0 63.0 63.0 62.0 23:41:09 INFO - 2015-12-15 23:41:09,515 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:78.00 stddev:1.17 (1.5%) stddev-sans-first:1.24 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: Values: 78.0 75.0 79.0 79.0 77.0 78.0 77.0 77.0 78.0 78.0 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:117.50 stddev:5.54 (4.7%) stddev-sans-first:5.72 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: Values: 111.0 117.0 108.0 109.0 119.0 122.0 118.0 110.0 122.0 110.0 23:41:09 INFO - 2015-12-15 23:41:09,516 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.30 Median:140.00 stddev:1.06 (0.8%) stddev-sans-first:1.12 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: Values: 139.0 140.0 141.0 140.0 138.0 140.0 140.0 138.0 139.0 138.0 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:1.35 (2.2%) stddev-sans-first:1.42 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 66.0 62.0 63.0 61.0 62.0 62.0 62.0 62.0 23:41:09 INFO - 2015-12-15 23:41:09,517 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:41:09 INFO - 2015-12-15 23:41:09,518 DEBUG : BROWSER_OUTPUT: 23:41:10 INFO - 2015-12-15 23:41:10,206 INFO : Browser exited with error code: 0 23:41:10 INFO - 2015-12-15 23:41:10,256 INFO : Completed test kraken (00:00:56) 23:41:10 INFO - 2015-12-15 23:41:10,256 INFO : Starting test v8_7 23:41:10 INFO - 2015-12-15 23:41:10,256 DEBUG : operating with platform_type : linux_ 23:41:10 INFO - 2015-12-15 23:41:10,256 INFO : Initialising browser for v8_7 test... 23:41:10 INFO - 2015-12-15 23:41:10,262 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYfGdZN/profile http://localhost:44289/getInfo.html 23:41:13 INFO - 2015-12-15 23:41:13,716 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 23:41:13 INFO - 2015-12-15 23:41:13,716 DEBUG : BROWSER_OUTPUT: colorDepth:24 23:41:13 INFO - 2015-12-15 23:41:13,716 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 23:41:13 INFO - 2015-12-15 23:41:13,716 DEBUG : BROWSER_OUTPUT: __metrics 23:41:13 INFO - 2015-12-15 23:41:13,877 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] 23:41:14 INFO - 2015-12-15 23:41:14,832 INFO : Browser initialized. 23:41:14 INFO - 2015-12-15 23:41:14,833 INFO : Running cycle 1/1 for v8_7 test... 23:41:14 INFO - 2015-12-15 23:41:14,833 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpYfGdZN/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 23:41:26 INFO - 2015-12-15 23:41:26,476 DEBUG : BROWSER_OUTPUT: RSS: Main: 129265664 23:41:26 INFO - 2015-12-15 23:41:26,477 DEBUG : BROWSER_OUTPUT: 23:41:47 INFO - 2015-12-15 23:41:47,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44289/tests/v8_7/run.html (next: http://localhost:44289/tests/v8_7/run.html) 23:41:48 INFO - 2015-12-15 23:41:48,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 429260800 23:41:48 INFO - 2015-12-15 23:41:48,112 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: __start_tp_report 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: |0;Richards;166.47244880972198 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.29153819801303 23:41:48 INFO - 2015-12-15 23:41:48,113 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.0567734682406 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4990.049751243781 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |4;RayTrace;941.6195856873823 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |5;Earley;555.247084952804 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |7;RegExp;23418.60465116279 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |8;Splay;540.3659849300323 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: __end_tp_report 23:41:48 INFO - 2015-12-15 23:41:48,114 DEBUG : BROWSER_OUTPUT: __startTimestamp1450251708109__endTimestamp 23:41:48 INFO - 2015-12-15 23:41:48,115 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,115 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 23:41:48 INFO - 2015-12-15 23:41:48,115 DEBUG : BROWSER_OUTPUT: Number of tests: 10 23:41:48 INFO - 2015-12-15 23:41:48,115 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,115 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.47 Median:166.47 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,116 DEBUG : BROWSER_OUTPUT: Values: 166.5 23:41:48 INFO - 2015-12-15 23:41:48,116 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,116 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.29 Median:171.29 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,116 DEBUG : BROWSER_OUTPUT: Values: 171.3 23:41:48 INFO - 2015-12-15 23:41:48,116 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.06 Median:281.06 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: Values: 281.1 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4990.05 Median:4990.05 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: Values: 4990.0 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:941.62 Median:941.62 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,117 DEBUG : BROWSER_OUTPUT: Values: 941.6 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:555.25 Median:555.25 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: Values: 555.2 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: Values: 10437.5 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,118 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23418.60 Median:23418.60 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: Values: 23418.6 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:540.37 Median:540.37 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: Values: 540.4 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: Values: 4822.1 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 23:41:48 INFO - 2015-12-15 23:41:48,119 DEBUG : BROWSER_OUTPUT: 23:41:48 INFO - 2015-12-15 23:41:48,785 INFO : Browser exited with error code: 0 23:41:48 INFO - 2015-12-15 23:41:48,802 INFO : Completed test v8_7 (00:00:38) 23:41:49 INFO - 2015-12-15 23:41:49,156 INFO : Completed test suite (00:20:04) 23:41:49 INFO - 2015-12-15 23:41:49,156 DEBUG : Working with test: dromaeo_css 23:41:49 INFO - 2015-12-15 23:41:49,156 DEBUG : Generating results file: dromaeo_css 23:41:49 INFO - 2015-12-15 23:41:49,158 DEBUG : Working with test: kraken 23:41:49 INFO - 2015-12-15 23:41:49,158 DEBUG : Generating results file: kraken 23:41:49 INFO - 2015-12-15 23:41:49,158 INFO : javascript benchmark 23:41:49 INFO - 2015-12-15 23:41:49,158 DEBUG : Working with test: v8_7 23:41:49 INFO - 2015-12-15 23:41:49,158 DEBUG : Generating results file: v8_7 23:41:49 INFO - 2015-12-15 23:41:49,159 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:41:49 INFO - 2015-12-15 23:41:49,312 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 23:41:49 INFO - 2015-12-15 23:41:49,312 DEBUG : process_Request line: dromaeo_css 7355.97 graph.html#tests=[[72,53,35]] 23:41:49 INFO - 2015-12-15 23:41:49,312 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:41:49 INFO - 2015-12-15 23:41:49,423 DEBUG : process_Request line: kraken 1464.90 graph.html#tests=[[232,53,35]] 23:41:49 INFO - 2015-12-15 23:41:49,423 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:41:49 INFO - 2015-12-15 23:41:49,543 DEBUG : process_Request line: v8_7 23275.13 graph.html#tests=[[230,53,35]] 23:41:49 INFO - 2015-12-15 23:41:49,545 INFO : javascript benchmark 23:41:49 INFO - 2015-12-15 23:41:49,548 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3303.6365999740024, 3406.5230867678515, 3439.29494453637, 3444.5005474206223, 3462.948055779163, 5740.024195886699, 5740.196372507852, 5748.798792042279, 5749.223854779603, 5758.078707406821, 5756.683382413976, 5773.711314434283, 5790.594658373915, 5798.362180160179, 5821.476067153955, 5090.516400941923, 5242.082635538775, 5263.131583288753, 5284.788608455677, 5322.0686379883555, 5168.819091331804, 5169.844904652848, 5175.792968281265, 5180.974095129519, 5185.222216667492, 5134.229865520165, 5197.896042079156, 5225.843224703246, 5240.082985477546, 5249.343832020997, 5177.534021938025, 5325.4142044375085, 5326.387465441485, 5327.200919862013, 5328.387235467912, 5233.633645644806, 5277.683338999673, 5279.128943724281, 5295.364556253275, 5296.576273898079, 5310.867228319285, 5438.238646589481, 5473.917891231635, 5600.187972743929, 5607.0, 6182.412670796267, 6212.627242365473, 6213.03697926822, 6214.067889816563, 6223.502119830403, 6229.6573688447115, 6243.594166379171, 6244.906326405062, 6246.625202487865, 6254.46837018854, 5553.0837411827, 5681.602287839853, 5692.971535142339, 5806.88386232273, 5813.04084825999, 6276.968615156895, 6305.085762564402, 6360.968195159087, 6363.745450181941, 6371.203599550056, 6098.817035488943, 6133.84665383369, 6141.7543298267565, 6146.661933593606, 6173.043178307369, 6003.7898673546215, 6085.604435711664, 6115.357887421846, 6203.875922481525, 6222.097795819581, 6169.691515424211, 6170.907436388457, 6172.321044685081, 6176.135341052256, 6199.194104766441, 5906.320773111147, 6146.0473626587955, 6159.291681456601, 6160.168377269101, 6164.167837341902, 4593.885152871205, 4643.976780116078, 4660.883477913011, 4668.85993420198, 4688.367070445514, 4173.499180098347, 4179.352200408942, 4192.035831758712, 4207.915841683149, 4217.40956266123, 5641.633293835969, 5731.398203188689, 5812.767489300465, 5817.854553636193, 5818.214541036906, 6083.878322433527, 6136.7948077883175, 6150.0, 6171.413715697002, 6178.135061091451, 5840.445157710011, 5883.970580147072, 5886.087656413263, 5892.204552385459, 5904.055351143882, 5236.3716354037, 5317.601179911522, 5360.035193665178, 5361.303030605996, 5364.22218778275, 6368.203974503187, 6371.713272902731, 6373.3945275198785, 6378.330275321117, 6380.170577824853, 6205.2553693557065, 6209.534284928558, 6209.875802484015, 6213.285472170758, 6217.191765070512, 6138.935378312821, 6143.723532440961, 6263.091851681572, 6342.112104305494, 6372.107904893412, 5989.490893274121, 6005.489533389711, 6007.158997740407, 6032.1856549365275, 6065.636061836304, 6032.547558933185, 6049.818505444844, 6052.0, 6057.969710151421, 6101.908471373022, 5818.709064546841, 5963.2844058713235, 5966.761329546943, 6138.355472675484, 6143.815685529441, 6068.666223357758, 6069.0592958091565, 6074.0, 6086.482648974887, 6095.847603809941, 5697.4302569742695, 5922.466977972003, 5965.463108320272, 5977.432143946318, 5978.611390259619, 5913.231279933753, 6011.188489553854, 6016.217891674054, 6034.877927254609, 6057.848553786014, 5835.503982161564, 5839.824805255849, 5842.474177324061, 5852.239208902815, 5923.555733319932], "unit": "ms", "name": "dojo.html", "value": 5667.106960667022}, {"lowerIsBetter": false, "replicates": [635.3297271378696, 635.6313338263808, 640.7917426836278, 652.6377860287541, 657.6974591687823, 44306.33540496891, 45243.77378113105, 45247.54752452474, 45259.32111018333, 45318.09363812722, 48377.27434088491, 48938.51061489384, 48975.26537101938, 48996.51003489964, 49080.75459622697, 8223.876641850347, 8330.458520196198, 9253.861192082124, 9453.149216570508, 10654.934506549322, 10808.567657293737, 10948.835767463494, 10953.123750099972, 10960.232783705143, 10960.451977401139, 10979.451027448635, 11077.612283570077, 11090.611828586001, 11091.72270693231, 11098.778024439507, 10946.45267736614, 11047.889521104848, 11055.834162487568, 11057.281276716987, 11059.72350691231, 10724.088452481548, 10812.459377031195, 10829.133669306437, 10840.728981775519, 10849.132069434427, 44702.55297447016, 45082.098358032985, 45175.548244517464, 45190.0, 45201.77399113016, 3266.6896644818703, 3278.246003419203, 3624.5288112545463, 4124.938125928114, 4152.937705934413, 33740.49389259139, 33873.32253354919, 34237.82881085579, 34297.657023429696, 34473.482897756556, 3949.822257998397, 3958.1094253792953, 4007.198560287925, 4014.5182578090526, 4033.657139143176, 315155.0, 319439.0, 320276.0, 320834.0, 321378.0, 61289.38710612926, 62220.37779622146, 68944.0, 69319.65340173368, 69328.65335673289, 75019.0, 76067.61966190134, 76417.61791191119, 76520.23479765242, 76522.61738691271, 17871.0, 18156.909215453838, 18167.818321816878, 18184.090795460175, 18308.176132073822, 13756.587302380945, 13795.241261730702, 13796.517121900686, 13819.792703109462, 13837.377318020714, 22601.547969040857, 23016.539669206522, 23087.653685194735, 23190.65214021791, 23241.651375229387, 8640.481571105754, 8844.690435834715, 8867.334949878785, 8875.201231889161, 9059.547022648841, 968.5109019945023, 1052.894710528941, 1105.5522513382093, 1107.634480621393, 1158.461315488302, 30398.54402183969, 30410.847945760128, 30575.237299681085, 31327.843360783507, 31768.682313176574, 1106.5684383090602, 1135.6706555099095, 1156.8091264941359, 1201.9900497512438, 1241.0567968343944, 13656.726865462833, 15897.364105435652, 16012.759808602881, 16177.676446471005, 16468.752968705478, 375119.0, 375196.0, 376947.0, 378182.0, 380517.0, 59217.703911481025, 60127.699361503786, 60292.397076029556, 60402.69798650979, 60683.0, 1463.9752173478607, 1464.421553486367, 1480.4670318685264, 1497.5731916403772, 1504.9398024078914, 31174.688253118085, 31202.375952481278, 31530.211744706565, 31636.36727265488, 31652.841735791175, 52713.7364313176, 52832.0, 53056.734716327715, 53108.46891531035, 53153.46846531485, 350458.0, 351707.0, 352562.0, 353083.0, 353863.0, 31494.055178344686, 32095.35809283848, 37059.444108337855, 47661.52338476571, 47723.5227647733, 51241.23138153005, 52206.47793522168, 53173.734131329096, 53900.46099539111, 53991.46008539864, 42614.0, 42933.35599966066, 46743.29885051795, 46895.531044690484, 47924.25127721018, 42941.71174864759, 43449.782751086044, 43472.56527434812, 43608.78195609002, 44088.338674920546, 43351.0, 46858.531414685414, 47325.290120648904, 47794.52205477901, 48292.27561586649, 36821.81589092038, 37897.0, 37911.81044094762, 38986.80506597449, 39154.4126838092, 29535.55696664595, 29636.407271854012, 29638.0, 29764.85117574398, 29859.70140298569], "unit": "ms", "name": "ext.html", "value": 21492.45212036011}, {"lowerIsBetter": false, "replicates": [395.5332707405264, 398.6392314954967, 398.84245722939437, 401.16557560274634, 402.2417742555284, 18464.723029154557, 18559.90720046401, 18899.905500472494, 20411.897940510313, 20446.38660840177, 18305.450836474894, 18794.34219802307, 19063.142158602863, 19074.904625476887, 19396.22415103394, 5762.711864406784, 5788.739506722208, 5868.530517558585, 5875.735591898375, 5883.146943693162, 5904.321003084658, 5934.910976335358, 5937.079752638348, 5937.406259374071, 5938.0, 4364.388985542027, 4475.060237350144, 5880.0, 5899.675517846516, 6254.968725156368, 6357.17356743625, 6363.172787537591, 6404.42360187583, 6410.1987251593555, 6415.358464153571, 5593.077142271521, 5684.8010319639025, 5687.0, 5687.40282270364, 5692.260006199168, 22632.886835565718, 22949.88525057364, 23542.411439713975, 23618.409539761473, 23673.526529469487, 2196.5042530595188, 2294.724633044029, 2304.5390921815538, 2374.192774456693, 2377.4413012941945, 10673.519691613896, 10970.835437468444, 11212.383318917457, 14805.259737013106, 15036.774448383283, 1847.7690288713911, 1849.3434830635065, 1850.1766713812358, 1857.721341798741, 1873.175802646831, 67299.3270067293, 67662.61686915635, 68743.0, 69285.30714692839, 69571.65214173897, 7983.241592048862, 8009.879851802228, 8023.6790528378215, 8084.151164127682, 8086.070101938235, 9643.469609171563, 9653.662121825702, 9667.27495437845, 9671.613135474643, 9680.612775489042, 6409.391107844747, 6457.709403076873, 6500.642464664489, 6502.187226596676, 6508.837279068061, 6637.966810165918, 6718.294579069225, 6737.393634572912, 6769.424598909148, 6782.389584937379, 3668.532586965235, 3690.0590349460717, 3698.4082546792383, 3721.7208709346905, 3723.3298006358573, 1261.2243470265903, 1269.6444995401303, 1270.3457719274581, 1276.5978716704208, 1322.1868550841164, 433.7787728258548, 478.17276112325874, 488.7756170792214, 501.92471129330164, 511.40166005773335, 11557.653270401901, 12708.618741437771, 12733.0, 13005.024623153113, 13888.4444622216, 530.8911673107003, 541.0153520592484, 546.4016901492859, 551.2998491915246, 557.7936163619487, 5769.307683077974, 7403.407727381797, 7523.924760752432, 7531.397488200931, 7628.80927976805, 603049.0, 605197.0, 620345.0, 624253.0, 624617.0, 66283.0, 84160.57919710362, 84246.15753842382, 88942.55528722444, 89232.0, 588.8468998060536, 606.130203158469, 607.2682417686677, 608.6804427675418, 608.6986941464004, 11946.761064778482, 12166.695832604255, 12399.566015189424, 12406.689832754253, 12471.189372690746, 38480.23039539137, 38573.4213986796, 38957.0, 40166.0, 40219.798901005306, 52293.7385313071, 53330.46669533255, 53391.199132013826, 54146.45853541414, 54272.728636356565, 7468.925310746823, 7529.002608371631, 7643.350315223268, 7732.458727889219, 7739.458237923292, 13090.083694141318, 13518.729625407634, 13532.255725935169, 13698.794518082037, 13757.518486852912, 11683.240589361665, 11871.584494542649, 12051.156419050581, 12100.636980890587, 12173.756524869275, 11115.0, 11863.822042669195, 11872.881271187523, 11917.76164476723, 12007.579734709243, 11917.880821192024, 12006.519739210335, 12056.276623402624, 12181.939090304491, 12183.0, 7748.0, 7789.805254868447, 7857.2928436441, 7865.292123708894, 7876.881846772418, 12188.085893557842, 12188.878111218773, 12221.572244971383, 12579.87420125787, 12629.0], "unit": "ms", "name": "jquery.html", "value": 8069.046940998265}, {"lowerIsBetter": false, "replicates": [1477.9926100369496, 1528.2740698168368, 1645.835416458355, 1737.739339099135, 1897.3454158315376, 7216.567005979645, 7330.413566914647, 8002.719904803325, 8467.91532084679, 8474.533900635462, 7991.520508769478, 8179.91820081799, 8266.214709602593, 8268.503889766618, 8271.83456330873, 2388.7850093491625, 2415.915442959497, 2416.178499310234, 2417.492326611405, 2417.6736140620965, 2154.1814110637933, 2195.2865318771383, 2370.95258094838, 2414.1188466209796, 2465.1125594785863, 2405.0259644843777, 2407.16952651335, 2410.8915098820594, 2412.0472413396697, 2413.2760171948435, 2437.7805997460223, 2462.753724627541, 2464.0636558107894, 2464.18681834994, 2466.3464181991785, 2400.7359190489033, 2441.4872876695736, 2443.0716327795326, 2444.1201167579657, 2444.877756112205, 8339.332853371716, 8359.540225287607, 8378.078411374805, 8396.874046889301, 8397.874031889527, 5025.472325405853, 5089.643724939256, 5100.719460429675, 5101.668391554575, 5103.974480127576, 3322.318924620447, 3363.260082781784, 3392.5928888533285, 3421.401254780428, 3433.4163192257374, 1128.086250137383, 1132.2470557079532, 1133.4502766158407, 1163.2555164694602, 1185.5317149725897, 11511.827322590168, 11517.424128793611, 11531.250468719507, 11553.595624153115, 11556.306621602731, 4762.78567464465, 4764.547368000104, 4784.832530861473, 4810.39870016248, 4823.348847905488, 9745.951270243746, 9750.366226195274, 9766.121049105615, 9778.119969202806, 9800.803983920281, 5182.533571978464, 5186.611004174702, 5195.19474481456, 5195.610329225323, 5208.010478009166, 4719.551642593948, 4729.834455794031, 4736.171170045297, 4744.264638980963, 4749.4063242094735, 3519.1026288296835, 3924.843006279774, 3994.360902255625, 4007.1785284016705, 4036.466142865751, 1234.7283597608512, 1269.8476182858117, 1294.1911305434103, 1295.2487557216791, 1298.1626850681257, 1198.856137263534, 1443.5092068696692, 1476.7415702252063, 1478.2682572126866, 1481.274175653922, 7841.529508229411, 7880.763577092696, 7959.602019899097, 7971.322437592754, 7980.3615710743, 1149.3219000789575, 1165.3299352872132, 1185.3125187391292, 1212.3029258176584, 1214.332117335462, 6335.334789847092, 6363.5863668861375, 6416.390442907917, 6425.742970281136, 6429.421352078241, 9725.0761177688, 9911.355761875455, 9915.553800079013, 9938.503074846229, 9964.601415943425, 9746.122848943629, 9911.355761875455, 9938.751531211632, 9951.651692190884, 10009.749756256006, 7739.535627862347, 7741.148473667892, 7789.766307010798, 7793.610319484003, 7804.453688241882, 7586.0, 7633.503822251536, 7649.847003059796, 7650.2349765023055, 7669.501482403626, 8920.063393343866, 9090.500022498825, 9108.726738197865, 9151.816963660822, 9154.908450915673, 8975.371723979259, 9124.406913550598, 9230.076992300716, 9270.675526356545, 9281.39670921388, 7541.7360392386, 7611.200823913631, 7617.390608751176, 7617.466777325534, 7618.428617853794, 7364.521306115085, 7562.810929726801, 7581.090269167735, 7876.133625301327, 8186.590670466334, 7509.8498030038, 7631.923680763121, 7670.69317227327, 7745.845083098419, 7793.64928578204, 7381.9630901847295, 7432.739854105081, 7534.133574638987, 7570.470067095251, 7660.616969151632, 7589.848203036019, 7595.050618672666, 7651.043619547557, 7655.081390232985, 7714.842773584007, 7048.506604537633, 7092.6099064551945, 7095.46783991213, 7101.786946391617, 7111.040009598638, 7080.398166155935, 7091.936209568606, 7209.6034718091005, 7243.0946131733535, 7277.672504737194], "unit": "ms", "name": "mootools.html", "value": 4857.98433366421}, {"lowerIsBetter": false, "replicates": [361.32432351502695, 363.4530032301386, 372.6720485972344, 376.1517777411935, 385.87651951375574, 5912.763489460416, 5954.612950158236, 6059.818205453832, 6096.1160631708435, 6101.115338275948, 5852.882942341151, 5921.171036054956, 5935.436133567304, 5950.0, 5978.970105149479, 4661.254199328108, 4680.836170734016, 4698.107359601682, 4703.388559487262, 4703.929441058387, 4533.184026875161, 4590.724556526602, 4658.743769092698, 4697.78859951303, 4707.670463067587, 4661.114388266218, 4678.461976872669, 4693.624510039189, 4695.90608187836, 4702.177119004177, 4663.463701674317, 4672.065586882603, 4675.836345727883, 4676.97661511692, 4684.2973553967, 4169.207850508394, 4173.5409104998425, 4176.018635620627, 4178.0, 4180.7909604519955, 5574.86062848432, 5678.829635110953, 5680.488756011979, 5685.800996965087, 5778.508826749731, 5506.366767821712, 5506.394296627368, 5507.228987941691, 5510.504054635102, 5510.586705997026, 6349.809505714836, 6357.5867568608355, 6370.90443643341, 6374.330695276975, 6383.968080159616, 4577.450705915278, 4645.581897629229, 4655.953440465586, 4657.161710892038, 4662.696924699883, 6588.439982601484, 6620.900686489658, 6621.966890165567, 6628.403443690091, 6634.767783127568, 4793.18515852306, 4794.640401969797, 4797.328374027639, 4799.616030717534, 4800.231962885921, 4839.491853355417, 4841.104395686809, 4845.878853028703, 4850.830220942321, 4851.175300198904, 5361.169018802092, 5473.370562385297, 5474.096774032319, 5475.342958844964, 5484.478974497416, 6355.269144048401, 6381.3937675920715, 6390.8721825563225, 6402.007688808242, 6403.359664033559, 1725.1632958015352, 1726.7841519810024, 1727.0760143323514, 1727.6803791298398, 1728.2568495546784, 1179.168686076314, 1191.0709646475764, 1192.898603618702, 1211.927284362941, 1227.110344999868, 6178.320384757673, 6247.656378899113, 6248.0, 6250.562460627803, 6251.0, 6386.07401926718, 6454.612723236621, 6551.050097735897, 6560.212774467094, 6587.637679927554, 6144.109104179869, 6156.6921653917125, 6174.567780255339, 6178.042403427476, 6183.876322473526, 3587.408077667156, 3632.164602141522, 3640.399334109842, 3642.21692336149, 3647.85408583659, 6543.214814222324, 6623.701933412912, 6681.632510211888, 6687.130673012574, 6720.8319792005595, 6657.633830139292, 6686.732530698815, 6692.832679182962, 6768.627725475146, 6800.6939687714175, 6799.456043516507, 6803.7618683345845, 6819.965900170468, 6822.624755638488, 6827.2490026098085, 6474.223093228843, 6570.8028759137305, 6628.53600247978, 6637.303083176197, 6637.601743895381, 6376.808695739135, 6424.486041116793, 6447.129637498878, 6457.418832305115, 6463.418292353711, 6718.22740384862, 6731.124953755981, 6760.18877734675, 6768.695408706521, 6769.6460707859615, 4541.434377763829, 4543.5910768031035, 4582.862514124582, 4601.28680054592, 4607.723536587815, 6663.0, 6670.032845237511, 6675.599464032173, 6678.866422671616, 6680.398764111059, 6452.064450654722, 6478.481721462366, 6486.4486518646445, 6489.156409666713, 6492.870142597028, 6373.840653983688, 6479.287278399466, 6483.319251478715, 6492.188476440445, 6495.415412612888, 6373.075903993988, 6376.649284289409, 6386.648734319471, 6387.872242555216, 6388.361163883575, 6466.450351719968, 6472.773452929125, 6494.447971922439, 6496.967515162394, 6498.740050397932, 6058.606190597597, 6151.96924015377, 6289.213848268966, 6294.181756371642, 6306.0225665022], "unit": "ms", "name": "prototype.html", "value": 4901.21861928677}, {"lowerIsBetter": false, "replicates": [944.3012170993467, 951.7620594851287, 956.2015716876409, 965.329096278087, 965.7730433348162, 14329.06861054032, 14690.412383504647, 14716.117032978002, 14731.48439804607, 14739.336729847157, 15621.140837253946, 15879.285432155551, 15884.444044458447, 15898.0, 15920.203989800522, 765.8085478630343, 768.5888049893308, 914.0448231597996, 915.0300681277866, 925.319889880937, 908.8091500784843, 913.7121806630923, 917.4403613795613, 918.5177781664646, 919.1084647891535, 951.2532661860441, 955.9091886270793, 957.0333962697656, 957.2437774158441, 958.6692591056075, 944.527736131934, 947.7393716727886, 947.9289053320994, 948.1845612773044, 948.4451595816456, 696.9442444604471, 697.2783169419627, 701.0745815523511, 701.3512500936613, 702.0452185028357, 15040.247987600576, 15079.773803392958, 15091.24543772818, 15102.622434439118, 15136.0, 11641.301521908712, 11667.416629168592, 11759.17685761997, 11815.468303926344, 12005.93997030018, 10553.630622928238, 10789.514471848786, 10822.0, 11052.336859788358, 11181.161412894066, 3434.10713214566, 3443.500692399588, 3450.0, 3485.1809824691177, 3538.6461353864665, 49452.50547494551, 49617.75191124057, 49840.501594984315, 50136.247956280684, 50565.24152137721, 8581.570921454027, 8659.220670139717, 8660.13398660129, 8690.0, 8718.65125394977, 14210.289485525682, 14224.14655120696, 14258.215798130996, 14265.64335891611, 14278.357473913698, 7293.380062694624, 7370.078740157481, 7410.147832999166, 7471.701131954661, 7605.049368828896, 4541.114482675913, 4549.863504094882, 4573.291139873325, 4623.86128416148, 4647.767611619473, 2956.6452025756616, 2973.3755911258877, 2978.5532170174647, 2987.462256793798, 2996.865141068657, 450.1020464173994, 451.62740738890545, 456.6757602102478, 462.20270034191424, 464.21547584582623, 541.94591519495, 666.5700623098171, 671.8589096289736, 693.9410150137194, 700.733721185946, 10105.595776169019, 10218.591256349811, 10471.47642617866, 10526.789464210673, 10546.472676366151, 719.7552832037132, 723.6888138100562, 737.5169264131895, 748.5620911766568, 753.574230559743, 4607.723536587815, 4741.762911854394, 4819.855404337875, 4830.212821256157, 5011.6241281904, 63724.681376592824, 63879.041814372824, 63970.0, 65722.34277657258, 65994.67002664956, 57269.14096288559, 57528.42471575314, 57698.0, 59128.704356477945, 59879.10181347283, 54478.09326673547, 54733.72633136809, 54761.45238547563, 55573.44426555683, 56137.157942630896, 9927.255455840696, 9941.602335906482, 10002.649907253211, 10123.79752404933, 10252.743681408025, 40529.392059118545, 40576.1884762309, 40643.0, 40914.590854091075, 41523.58476415197, 47591.286130707376, 49652.75173624109, 50305.24542131944, 50464.495355047446, 50583.241251381995, 9170.312226582899, 9254.768630784285, 9264.583093760662, 9306.906930930874, 9406.576704048472, 13826.032177747462, 14102.858971410566, 14221.71556568842, 14273.072250303698, 14273.928630356782, 13293.534726284533, 13485.39315730814, 13652.24912629815, 14097.788533171806, 14464.349104290122, 13342.86657133455, 13356.866431335562, 13372.0, 13530.458781648624, 13733.244671542761, 11450.312981221154, 12489.31308778026, 12911.0, 13482.0, 13808.585742427744, 10929.78140437171, 11039.0, 11081.445927703744, 11123.72190695239, 11312.0, 9222.440211587493, 10702.411367374558, 10777.89222107769, 10876.23866329381, 10995.285306455055], "unit": "ms", "name": "yui.html", "value": 6769.7156914176485}], "name": "dromaeo_css", "value": 7355.967496728809}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 109.0, 111.0, 111.0, 103.0, 111.0, 107.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.7}, {"lowerIsBetter": true, "replicates": [153.0, 121.0, 144.0, 159.0, 125.0, 143.0, 150.0, 122.0, 168.0, 129.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.4}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 160.0, 164.0, 162.0, 161.0, 161.0, 162.0, 162.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.7}, {"lowerIsBetter": true, "replicates": [71.0, 74.0, 70.0, 145.0, 71.0, 71.0, 71.0, 71.0, 152.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 86.6}, {"lowerIsBetter": true, "replicates": [90.0, 89.0, 143.0, 89.0, 90.0, 89.0, 139.0, 88.0, 89.0, 129.0], "unit": "ms", "name": "audio-oscillator", "value": 103.5}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 57.0, 57.0, 58.0, 57.0, 55.0, 57.0, 58.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 62.0, 62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 62.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 62.6}, {"lowerIsBetter": true, "replicates": [78.0, 75.0, 79.0, 79.0, 77.0, 78.0, 77.0, 77.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.6}, {"lowerIsBetter": true, "replicates": [111.0, 117.0, 108.0, 109.0, 119.0, 122.0, 118.0, 110.0, 122.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.6}, {"lowerIsBetter": true, "replicates": [139.0, 140.0, 141.0, 140.0, 138.0, 140.0, 140.0, 138.0, 139.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.3}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 66.0, 62.0, 63.0, 61.0, 62.0, 62.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.6}], "name": "kraken", "value": 1464.8999999999996}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.47244880972198], "unit": "score", "name": "Richards", "value": 212.05911400000002}, {"lowerIsBetter": false, "replicates": [171.29153819801303], "unit": "score", "name": "DeltaBlue", "value": 385.9968839999998}, {"lowerIsBetter": false, "replicates": [281.0567734682406], "unit": "score", "name": "Encrypt", "value": 947.0719979999998}, {"lowerIsBetter": false, "replicates": [4990.049751243781], "unit": "score", "name": "Decrypt", "value": 53.34235393818545}, {"lowerIsBetter": false, "replicates": [941.6195856873823], "unit": "score", "name": "RayTrace", "value": 785.8683179999997}, {"lowerIsBetter": false, "replicates": [555.247084952804], "unit": "score", "name": "Earley", "value": 1200.2998630000004}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [23418.60465116279], "unit": "score", "name": "RegExp", "value": 38.90005461767626}, {"lowerIsBetter": false, "replicates": [540.3659849300323], "unit": "score", "name": "Splay", "value": 150.80704980079685}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23275.12719972131}]} 23:41:49 INFO - RETURN: dromaeo_css: 7355.97 23:41:49 INFO - RETURN: kraken: 1464.90 23:41:49 INFO - RETURN: v8_7: 23275.13 23:41:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23275.13"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1464.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7355.97"}}} 23:41:49 INFO - Return code: 0 23:41:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:41:49 INFO - # TBPL SUCCESS # 23:41:49 INFO - Running post-action listener: _resource_record_post_action 23:41:49 INFO - Running post-run listener: _resource_record_post_run 23:41:50 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 0; Write bytes: 372420608; Read time: 0; Write time: 297224 23:41:50 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 67026944; Read time: 0; Write time: 68620 23:41:50 INFO - run-tests - Wall time: 1207s; CPU: 13.0%; Read bytes: 0; Write bytes: 264843264; Read time: 0; Write time: 209420 23:41:50 INFO - Running post-run listener: _upload_blobber_files 23:41:50 INFO - Blob upload gear active. 23:41:50 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 23:41:50 INFO - Copying logs to upload dir... 23:41:50 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.907621 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-12-15 23:41:50.045320) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 23:41:50.048502) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010139 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1450242068/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-15 23:41:50.105164) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:41:50.105503) ========= 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=a732b360c9c0c9a4a8cfc42c000001d9-1450239646.651018-2012630242 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-15 23:41:50.154938) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 23:41:50.155842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-15 23:41:50.156268) ========= ========= Total master_lag: 0.25 =========