builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1452529413.02 results: success (0) buildid: 20160111060133 builduid: 2f0768ec25b340c3891c45ecc1fb4bd2 revision: 51f069f6fe91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.024594) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.025086) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.025371) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006342 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.050431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.050778) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.074638) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.075006) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015241 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.124275) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.126609) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.126993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.127268) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-11 08:23:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-01-11 08:23:33 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164136 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.303415) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.303777) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.059060 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.375684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.376026) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51f069f6fe91 --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 51f069f6fe91 --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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-11 08:23:33,711 Setting DEBUG logging. 2016-01-11 08:23:33,711 attempt 1/10 2016-01-11 08:23:33,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/51f069f6fe91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-11 08:23:33,981 unpacking tar archive at: mozilla-beta-51f069f6fe91/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541402 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.930404) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.930731) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.941293) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.941588) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-11 08:23:33.941984) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-11 08:23:33.942260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:23:34 INFO - MultiFileLogger online at 20160111 08:23:34 in /builds/slave/test-pgo 08:23:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:23:34 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:23:34 INFO - {'append_to_log': False, 08:23:34 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:23:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:23:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:23:34 INFO - 'branch': 'Mozilla-Beta', 08:23:34 INFO - 'buildbot_json_path': 'buildprops.json', 08:23:34 INFO - 'config_files': ('talos/linux_config.py',), 08:23:34 INFO - 'default_actions': ('clobber', 08:23:34 INFO - 'read-buildbot-config', 08:23:34 INFO - 'download-and-extract', 08:23:34 INFO - 'populate-webroot', 08:23:34 INFO - 'create-virtualenv', 08:23:34 INFO - 'install', 08:23:34 INFO - 'run-tests'), 08:23:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:23:34 INFO - 'download_minidump_stackwalk': True, 08:23:34 INFO - 'download_symbols': 'ondemand', 08:23:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:23:34 INFO - 'tooltool.py': '/tools/tooltool.py', 08:23:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:23:34 INFO - '/tools/misc-python/virtualenv.py')}, 08:23:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:23:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:23:34 INFO - 'installer_path': 'installer.exe', 08:23:34 INFO - 'log_level': 'info', 08:23:34 INFO - 'log_name': 'talos', 08:23:34 INFO - 'log_to_console': True, 08:23:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:23:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:23:34 INFO - 'opt_config_files': (), 08:23:34 INFO - 'pip_index': False, 08:23:34 INFO - 'sps_profile': False, 08:23:34 INFO - 'sps_profile_interval': 0, 08:23:34 INFO - 'suite': 'dromaeojs', 08:23:34 INFO - 'system_bits': '32', 08:23:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:23:34 INFO - 'title': 'talos-linux64-ix-017', 08:23:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:23:34 INFO - 'use_talos_json': True, 08:23:34 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:23:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:23:34 INFO - 'work_dir': 'build'} 08:23:34 INFO - ##### 08:23:34 INFO - ##### Running clobber step. 08:23:34 INFO - ##### 08:23:34 INFO - Running pre-action listener: _resource_record_pre_action 08:23:34 INFO - Running main action method: clobber 08:23:34 INFO - rmtree: /builds/slave/test-pgo/build 08:23:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:23:35 INFO - Running post-action listener: _resource_record_post_action 08:23:35 INFO - ##### 08:23:35 INFO - ##### Running read-buildbot-config step. 08:23:35 INFO - ##### 08:23:35 INFO - Running pre-action listener: _resource_record_pre_action 08:23:35 INFO - Running main action method: read_buildbot_config 08:23:35 INFO - Using buildbot properties: 08:23:35 INFO - { 08:23:35 INFO - "properties": { 08:23:35 INFO - "buildnumber": 51, 08:23:35 INFO - "product": "firefox", 08:23:35 INFO - "script_repo_revision": "production", 08:23:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:23:35 INFO - "repository": "", 08:23:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:23:35 INFO - "buildid": "20160111060133", 08:23:35 INFO - "slavename": "talos-linux64-ix-017", 08:23:35 INFO - "pgo_build": "True", 08:23:35 INFO - "basedir": "/builds/slave/test-pgo", 08:23:35 INFO - "project": "", 08:23:35 INFO - "platform": "ubuntu64_hw", 08:23:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:23:35 INFO - "slavebuilddir": "test", 08:23:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:23:35 INFO - "branch": "mozilla-beta", 08:23:35 INFO - "repo_path": "releases/mozilla-beta", 08:23:35 INFO - "stage_platform": "linux64-pgo", 08:23:35 INFO - "builduid": "2f0768ec25b340c3891c45ecc1fb4bd2", 08:23:35 INFO - "revision": "51f069f6fe91" 08:23:35 INFO - }, 08:23:35 INFO - "sourcestamp": { 08:23:35 INFO - "repository": "", 08:23:35 INFO - "hasPatch": false, 08:23:35 INFO - "project": "", 08:23:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:23:35 INFO - "changes": [ 08:23:35 INFO - { 08:23:35 INFO - "category": null, 08:23:35 INFO - "files": [ 08:23:35 INFO - { 08:23:35 INFO - "url": null, 08:23:35 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2" 08:23:35 INFO - } 08:23:35 INFO - ], 08:23:35 INFO - "repository": "", 08:23:35 INFO - "rev": "51f069f6fe91", 08:23:35 INFO - "who": "sendchange", 08:23:35 INFO - "when": 1452529353, 08:23:35 INFO - "number": 7443189, 08:23:35 INFO - "comments": "Bug 1233925 - Treat functions with rest more like functions with lazy arguments. r=nbp a=ritu", 08:23:35 INFO - "project": "", 08:23:35 INFO - "at": "Mon 11 Jan 2016 08:22:33", 08:23:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:23:35 INFO - "revlink": "", 08:23:35 INFO - "properties": [ 08:23:35 INFO - [ 08:23:35 INFO - "buildid", 08:23:35 INFO - "20160111060133", 08:23:35 INFO - "Change" 08:23:35 INFO - ], 08:23:35 INFO - [ 08:23:35 INFO - "builduid", 08:23:35 INFO - "2f0768ec25b340c3891c45ecc1fb4bd2", 08:23:35 INFO - "Change" 08:23:35 INFO - ], 08:23:35 INFO - [ 08:23:35 INFO - "pgo_build", 08:23:35 INFO - "True", 08:23:35 INFO - "Change" 08:23:35 INFO - ] 08:23:35 INFO - ], 08:23:35 INFO - "revision": "51f069f6fe91" 08:23:35 INFO - } 08:23:35 INFO - ], 08:23:35 INFO - "revision": "51f069f6fe91" 08:23:35 INFO - } 08:23:35 INFO - } 08:23:35 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2. 08:23:35 INFO - Running post-action listener: _resource_record_post_action 08:23:35 INFO - ##### 08:23:35 INFO - ##### Running download-and-extract step. 08:23:35 INFO - ##### 08:23:35 INFO - Running pre-action listener: _resource_record_pre_action 08:23:35 INFO - Running main action method: download_and_extract 08:23:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/test_packages.json 08:23:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:23:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:23:35 INFO - Downloaded 1270 bytes. 08:23:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:23:35 INFO - Using the following test package requirements: 08:23:35 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 08:23:35 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:23:35 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'jsshell-linux-x86_64.zip'], 08:23:35 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:23:35 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 08:23:35 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 08:23:35 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 08:23:35 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:23:35 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 08:23:35 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 08:23:35 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:23:35 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.common.tests.zip 08:23:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:36 INFO - Downloaded 22572101 bytes. 08:23:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:23:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 08:23:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:23:38 INFO - Return code: 0 08:23:38 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 08:23:38 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')]} 08:23:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.common.tests.zip 08:23:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:39 INFO - Downloaded 22572101 bytes. 08:23:39 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 08:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 08:23:39 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 08:23:41 INFO - Return code: 0 08:23: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')]} 08:23:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 08:23:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:42 INFO - Downloaded 11131970 bytes. 08:23:42 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 08:23:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 08:23:42 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 08:23:42 INFO - Return code: 0 08:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2 08:23:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/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 08:23:56 INFO - Downloaded 56624176 bytes. 08:23:56 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2 08:23:56 INFO - mkdir: /builds/slave/test-pgo/properties 08:23:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:23:56 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:23:56 INFO - Contents: 08:23:56 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2 08:23:56 INFO - Running post-action listener: _resource_record_post_action 08:23:56 INFO - Running post-action listener: set_extra_try_arguments 08:23:56 INFO - ##### 08:23:56 INFO - ##### Running populate-webroot step. 08:23:56 INFO - ##### 08:23:56 INFO - Running pre-action listener: _resource_record_pre_action 08:23:56 INFO - Running main action method: populate_webroot 08:23:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:23:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:23:56 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 08:23:56 INFO - '../fennec_ids.txt'], 08:23:56 INFO - 'tests': ['tcheck2']}, 08:23:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:23:56 INFO - '--tppagecycles', 08:23:56 INFO - '7'], 08:23:56 INFO - 'tests': ['tsvgm']}}, 08:23:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:23:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:23:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:23:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:23:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:23:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:23:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'tests': ['damp', 'tps']}, 08:23:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['damp', 'tps']}, 08:23:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:23:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:23:56 INFO - 'other': {'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'other_l64': {'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'other_nol64': {'tests': ['a11yr', 08:23:56 INFO - 'ts_paint', 08:23:56 INFO - 'tpaint', 08:23:56 INFO - 'sessionrestore', 08:23:56 INFO - 'sessionrestore_no_auto_restore']}, 08:23:56 INFO - 'svgr': {'tests': ['tsvgx', 08:23:56 INFO - 'tsvgr_opacity', 08:23:56 INFO - 'tart', 08:23:56 INFO - 'tscrollx', 08:23:56 INFO - 'cart']}, 08:23:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['tsvgx', 08:23:56 INFO - 'tsvgr_opacity', 08:23:56 INFO - 'tart', 08:23:56 INFO - 'tscrollx', 08:23:56 INFO - 'cart']}, 08:23:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'tests': ['tp5o']}, 08:23:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'talos_options': ['--e10s'], 08:23:56 INFO - 'tests': ['tp5o']}, 08:23:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'talos_options': ['--xperf_path', 08:23:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:23:56 INFO - 'tests': ['tp5n']}, 08:23:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:23:56 INFO - 'talos_options': ['--e10s', 08:23:56 INFO - '--xperf_path', 08:23:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:23:56 INFO - 'tests': ['tp5n']}}, 08:23:56 INFO - 'talos.zip': {'path': '', 08:23:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:23:56 INFO - Running post-action listener: _resource_record_post_action 08:23:56 INFO - ##### 08:23:56 INFO - ##### Running create-virtualenv step. 08:23:56 INFO - ##### 08:23:56 INFO - Running pre-action listener: _resource_record_pre_action 08:23:56 INFO - Running main action method: create_virtualenv 08:23:56 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:23:56 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 08:23:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:23:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:23:56 INFO - Using real prefix '/usr' 08:23:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:23:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:23:59 INFO - Installing pip.................done. 08:23:59 INFO - Return code: 0 08:23:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:23:59 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 08:23:59 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 08:23:59 INFO - Using env: {'DISPLAY': ':0', 08:23:59 INFO - 'HOME': '/home/cltbld', 08:23:59 INFO - 'LANG': 'en_US.UTF-8', 08:23:59 INFO - 'LANGUAGE': 'en_US:en', 08:23:59 INFO - 'LOGNAME': 'cltbld', 08:23:59 INFO - 'MAIL': '/var/mail/cltbld', 08:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:59 INFO - 'MOZ_NO_REMOTE': '1', 08:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:23:59 INFO - 'NO_EM_RESTART': '1', 08:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:23:59 INFO - 'PWD': '/builds/slave/test-pgo', 08:23:59 INFO - 'SHELL': '/bin/bash', 08:23:59 INFO - 'SHLVL': '1', 08:23:59 INFO - 'TERM': 'linux', 08:23:59 INFO - 'TMOUT': '86400', 08:23:59 INFO - 'USER': 'cltbld', 08:23:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:59 INFO - '_': '/tools/buildbot/bin/python'} 08:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:59 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 08:23:59 INFO - Cleaning up... 08:23:59 INFO - Return code: 0 08:23:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:23:59 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 08:23:59 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 08:23:59 INFO - Using env: {'DISPLAY': ':0', 08:23:59 INFO - 'HOME': '/home/cltbld', 08:23:59 INFO - 'LANG': 'en_US.UTF-8', 08:23:59 INFO - 'LANGUAGE': 'en_US:en', 08:23:59 INFO - 'LOGNAME': 'cltbld', 08:23:59 INFO - 'MAIL': '/var/mail/cltbld', 08:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:59 INFO - 'MOZ_NO_REMOTE': '1', 08:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:23:59 INFO - 'NO_EM_RESTART': '1', 08:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:23:59 INFO - 'PWD': '/builds/slave/test-pgo', 08:23:59 INFO - 'SHELL': '/bin/bash', 08:23:59 INFO - 'SHLVL': '1', 08:23:59 INFO - 'TERM': 'linux', 08:23:59 INFO - 'TMOUT': '86400', 08:23:59 INFO - 'USER': 'cltbld', 08:23:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:59 INFO - '_': '/tools/buildbot/bin/python'} 08:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:23:59 INFO - Downloading/unpacking psutil>=0.7.1 08:23:59 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 08:23:59 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 08:23:59 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 08:23:59 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 08:23:59 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 08:23:59 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 08:24:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 08:24:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:24:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:24:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:01 INFO - Installing collected packages: psutil 08:24:01 INFO - Running setup.py install for psutil 08:24:01 INFO - building 'psutil._psutil_linux' extension 08:24:01 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 08:24:01 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 08:24:01 INFO - building 'psutil._psutil_posix' extension 08:24:01 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 08:24:02 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 08:24:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:02 INFO - Successfully installed psutil 08:24:02 INFO - Cleaning up... 08:24:02 INFO - Return code: 0 08:24:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:02 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:02 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 08:24:02 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 08:24:02 INFO - Using env: {'DISPLAY': ':0', 08:24:02 INFO - 'HOME': '/home/cltbld', 08:24:02 INFO - 'LANG': 'en_US.UTF-8', 08:24:02 INFO - 'LANGUAGE': 'en_US:en', 08:24:02 INFO - 'LOGNAME': 'cltbld', 08:24:02 INFO - 'MAIL': '/var/mail/cltbld', 08:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:02 INFO - 'MOZ_NO_REMOTE': '1', 08:24:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:02 INFO - 'NO_EM_RESTART': '1', 08:24:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:02 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:02 INFO - 'SHELL': '/bin/bash', 08:24:02 INFO - 'SHLVL': '1', 08:24:02 INFO - 'TERM': 'linux', 08:24:02 INFO - 'TMOUT': '86400', 08:24:02 INFO - 'USER': 'cltbld', 08:24:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:02 INFO - '_': '/tools/buildbot/bin/python'} 08:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:24:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:24:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:24:04 INFO - Installing collected packages: mozsystemmonitor 08:24:04 INFO - Running setup.py install for mozsystemmonitor 08:24:04 INFO - Successfully installed mozsystemmonitor 08:24:04 INFO - Cleaning up... 08:24:04 INFO - Return code: 0 08:24:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:24:04 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')]} 08:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:04 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')]} 08:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:04 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:04 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 08:24:04 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 08:24:04 INFO - Using env: {'DISPLAY': ':0', 08:24:04 INFO - 'HOME': '/home/cltbld', 08:24:04 INFO - 'LANG': 'en_US.UTF-8', 08:24:04 INFO - 'LANGUAGE': 'en_US:en', 08:24:04 INFO - 'LOGNAME': 'cltbld', 08:24:04 INFO - 'MAIL': '/var/mail/cltbld', 08:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:04 INFO - 'MOZ_NO_REMOTE': '1', 08:24:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:04 INFO - 'NO_EM_RESTART': '1', 08:24:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:04 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:04 INFO - 'SHELL': '/bin/bash', 08:24:04 INFO - 'SHLVL': '1', 08:24:04 INFO - 'TERM': 'linux', 08:24:04 INFO - 'TMOUT': '86400', 08:24:04 INFO - 'USER': 'cltbld', 08:24:04 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:04 INFO - '_': '/tools/buildbot/bin/python'} 08:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:24:04 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 08:24:04 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 08:24:04 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 08:24:04 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 08:24:04 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 08:24:04 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 08:24:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:24:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:24:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:24:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:24:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:24:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:06 INFO - Downloading docopt-0.6.1.tar.gz 08:24:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:24:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:24:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:24:07 INFO - Running setup.py install for blobuploader 08:24:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:24:07 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:24:07 INFO - Running setup.py install for requests 08:24:07 INFO - Running setup.py install for docopt 08:24:07 INFO - Successfully installed blobuploader requests docopt 08:24:07 INFO - Cleaning up... 08:24:07 INFO - Return code: 0 08:24:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:07 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:07 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 08:24:07 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 08:24:07 INFO - Using env: {'DISPLAY': ':0', 08:24:07 INFO - 'HOME': '/home/cltbld', 08:24:07 INFO - 'LANG': 'en_US.UTF-8', 08:24:07 INFO - 'LANGUAGE': 'en_US:en', 08:24:07 INFO - 'LOGNAME': 'cltbld', 08:24:07 INFO - 'MAIL': '/var/mail/cltbld', 08:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:07 INFO - 'MOZ_NO_REMOTE': '1', 08:24:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:07 INFO - 'NO_EM_RESTART': '1', 08:24:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:07 INFO - 'SHELL': '/bin/bash', 08:24:07 INFO - 'SHLVL': '1', 08:24:07 INFO - 'TERM': 'linux', 08:24:07 INFO - 'TMOUT': '86400', 08:24:07 INFO - 'USER': 'cltbld', 08:24:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:07 INFO - '_': '/tools/buildbot/bin/python'} 08:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:24:07 INFO - Running setup.py (path:/tmp/pip-hBkHXW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:24:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:24:07 INFO - Running setup.py (path:/tmp/pip-xgzWnG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:24:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:24:07 INFO - Running setup.py (path:/tmp/pip-pW7yBK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:24:08 INFO - Running setup.py (path:/tmp/pip-E7jZQI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:24:08 INFO - Running setup.py (path:/tmp/pip-_0d5Ib-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:24:08 INFO - Running setup.py (path:/tmp/pip-SLOXhH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:24:08 INFO - Running setup.py (path:/tmp/pip-7uehem-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:24:08 INFO - Running setup.py (path:/tmp/pip-iYZosx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:24:08 INFO - Running setup.py (path:/tmp/pip-P1l0jz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:24:08 INFO - Running setup.py (path:/tmp/pip-Qy7TTd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:24:08 INFO - Running setup.py (path:/tmp/pip-Obp0_N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:24:08 INFO - Running setup.py (path:/tmp/pip-7LeD8b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:24:08 INFO - Running setup.py (path:/tmp/pip-MZgFGX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:24:08 INFO - Running setup.py (path:/tmp/pip-vZnyRH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:24:08 INFO - Running setup.py (path:/tmp/pip-sS5E0w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:24:08 INFO - Running setup.py (path:/tmp/pip-FLupMW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:24:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:24:08 INFO - Running setup.py (path:/tmp/pip-P3qk94-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:24:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:24:09 INFO - Running setup.py install for manifestparser 08:24:09 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Running setup.py install for mozcrash 08:24:09 INFO - Running setup.py install for mozdebug 08:24:09 INFO - Running setup.py install for mozdevice 08:24:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Running setup.py install for mozfile 08:24:09 INFO - Running setup.py install for mozhttpd 08:24:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Running setup.py install for mozinfo 08:24:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Running setup.py install for mozInstall 08:24:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:24:09 INFO - Running setup.py install for mozleak 08:24:09 INFO - Running setup.py install for mozlog 08:24:10 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Running setup.py install for moznetwork 08:24:10 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Running setup.py install for mozprocess 08:24:10 INFO - Running setup.py install for mozprofile 08:24:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Running setup.py install for mozrunner 08:24:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Running setup.py install for mozscreenshot 08:24:10 INFO - Running setup.py install for moztest 08:24:10 INFO - Running setup.py install for mozversion 08:24:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:24:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:24:10 INFO - Cleaning up... 08:24:10 INFO - Return code: 0 08:24:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:24: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')]} 08:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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')]} 08:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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', '-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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:10 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 08:24:10 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 08:24:10 INFO - Using env: {'DISPLAY': ':0', 08:24:10 INFO - 'HOME': '/home/cltbld', 08:24:10 INFO - 'LANG': 'en_US.UTF-8', 08:24:10 INFO - 'LANGUAGE': 'en_US:en', 08:24:10 INFO - 'LOGNAME': 'cltbld', 08:24:10 INFO - 'MAIL': '/var/mail/cltbld', 08:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:10 INFO - 'MOZ_NO_REMOTE': '1', 08:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:10 INFO - 'NO_EM_RESTART': '1', 08:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:10 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:10 INFO - 'SHELL': '/bin/bash', 08:24:10 INFO - 'SHLVL': '1', 08:24:10 INFO - 'TERM': 'linux', 08:24:10 INFO - 'TMOUT': '86400', 08:24:10 INFO - 'USER': 'cltbld', 08:24:10 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:10 INFO - '_': '/tools/buildbot/bin/python'} 08:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:24:11 INFO - Running setup.py (path:/tmp/pip-I88Cp8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:24:11 INFO - Running setup.py (path:/tmp/pip-OJdxPL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:24:11 INFO - Running setup.py (path:/tmp/pip-KZGQOo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:24:11 INFO - Running setup.py (path:/tmp/pip-HshNvW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:24:11 INFO - Running setup.py (path:/tmp/pip-EmHcCJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:24:11 INFO - Running setup.py (path:/tmp/pip-ec9qPy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:24:11 INFO - Running setup.py (path:/tmp/pip-XYoyst-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:24:11 INFO - Running setup.py (path:/tmp/pip-jR0D33-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:24:11 INFO - Running setup.py (path:/tmp/pip-Ukywq3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:24:11 INFO - Running setup.py (path:/tmp/pip-UTErlk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:24:11 INFO - Running setup.py (path:/tmp/pip-c9EQlA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:24:11 INFO - Running setup.py (path:/tmp/pip-VHPCBB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:24:11 INFO - Running setup.py (path:/tmp/pip-KCZUtD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:24:11 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)) 08:24:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:24:11 INFO - Running setup.py (path:/tmp/pip-Maye2L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:24:12 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)) 08:24:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:24:12 INFO - Running setup.py (path:/tmp/pip-0Qlx5H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:24:12 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)) 08:24:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:24:12 INFO - Running setup.py (path:/tmp/pip-zdNmRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:24:12 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)) 08:24:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:24:12 INFO - Running setup.py (path:/tmp/pip-KKXCcN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:24:12 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)) 08:24:12 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)) 08:24:12 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)) 08:24:12 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)) 08:24:12 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)) 08:24:12 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)) 08:24:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:13 INFO - Downloading blessings-1.5.1.tar.gz 08:24:13 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 08:24:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:24:14 INFO - Installing collected packages: blessings 08:24:14 INFO - Running setup.py install for blessings 08:24:14 INFO - Successfully installed blessings 08:24:14 INFO - Cleaning up... 08:24:15 INFO - Return code: 0 08:24:15 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:24:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:24:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:24:15 INFO - Reading from file tmpfile_stdout 08:24:15 INFO - Current package versions: 08:24:15 INFO - argparse == 1.2.1 08:24:15 INFO - blessings == 1.5.1 08:24:15 INFO - blobuploader == 1.2.4 08:24:15 INFO - docopt == 0.6.1 08:24:15 INFO - manifestparser == 1.1 08:24:15 INFO - mozInstall == 1.12 08:24:15 INFO - mozcrash == 0.16 08:24:15 INFO - mozdebug == 0.1 08:24:15 INFO - mozdevice == 0.46 08:24:15 INFO - mozfile == 1.2 08:24:15 INFO - mozhttpd == 0.7 08:24:15 INFO - mozinfo == 0.8 08:24:15 INFO - mozleak == 0.1 08:24:15 INFO - mozlog == 3.0 08:24:15 INFO - moznetwork == 0.27 08:24:15 INFO - mozprocess == 0.22 08:24:15 INFO - mozprofile == 0.27 08:24:15 INFO - mozrunner == 6.11 08:24:15 INFO - mozscreenshot == 0.1 08:24:15 INFO - mozsystemmonitor == 0.0 08:24:15 INFO - moztest == 0.7 08:24:15 INFO - mozversion == 1.4 08:24:15 INFO - psutil == 3.1.1 08:24:15 INFO - requests == 1.2.3 08:24:15 INFO - wsgiref == 0.1.2 08:24:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:15 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:15 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 08:24:15 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 08:24:15 INFO - Using env: {'DISPLAY': ':0', 08:24:15 INFO - 'HOME': '/home/cltbld', 08:24:15 INFO - 'LANG': 'en_US.UTF-8', 08:24:15 INFO - 'LANGUAGE': 'en_US:en', 08:24:15 INFO - 'LOGNAME': 'cltbld', 08:24:15 INFO - 'MAIL': '/var/mail/cltbld', 08:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:15 INFO - 'MOZ_NO_REMOTE': '1', 08:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:15 INFO - 'NO_EM_RESTART': '1', 08:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:15 INFO - 'SHELL': '/bin/bash', 08:24:15 INFO - 'SHLVL': '1', 08:24:15 INFO - 'TERM': 'linux', 08:24:15 INFO - 'TMOUT': '86400', 08:24:15 INFO - 'USER': 'cltbld', 08:24:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:15 INFO - '_': '/tools/buildbot/bin/python'} 08:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 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)) 08:24:15 INFO - Cleaning up... 08:24:15 INFO - Return code: 0 08:24:15 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:15 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:15 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 08:24:15 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 08:24:15 INFO - Using env: {'DISPLAY': ':0', 08:24:15 INFO - 'HOME': '/home/cltbld', 08:24:15 INFO - 'LANG': 'en_US.UTF-8', 08:24:15 INFO - 'LANGUAGE': 'en_US:en', 08:24:15 INFO - 'LOGNAME': 'cltbld', 08:24:15 INFO - 'MAIL': '/var/mail/cltbld', 08:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:15 INFO - 'MOZ_NO_REMOTE': '1', 08:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:15 INFO - 'NO_EM_RESTART': '1', 08:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:15 INFO - 'SHELL': '/bin/bash', 08:24:15 INFO - 'SHLVL': '1', 08:24:15 INFO - 'TERM': 'linux', 08:24:15 INFO - 'TMOUT': '86400', 08:24:15 INFO - 'USER': 'cltbld', 08:24:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:15 INFO - '_': '/tools/buildbot/bin/python'} 08:24:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:16 INFO - Downloading/unpacking jsonschema 08:24:16 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 08:24:16 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 08:24:16 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 08:24:16 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 08:24:16 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 08:24:16 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 08:24:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:24:17 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 08:24:17 INFO - Downloading/unpacking functools32 (from jsonschema) 08:24:17 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 08:24:17 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 08:24:17 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 08:24:17 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 08:24:17 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 08:24:17 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 08:24:17 INFO - Downloading functools32-3.2.3-2.tar.gz 08:24:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 08:24:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:24:17 INFO - warning: no files found matching '*.txt' 08:24:17 INFO - no previously-included directories found matching 'build' 08:24:17 INFO - no previously-included directories found matching 'dist' 08:24:17 INFO - no previously-included directories found matching '.git*' 08:24:17 INFO - Installing collected packages: jsonschema, functools32 08:24:18 INFO - Running setup.py install for functools32 08:24:18 INFO - warning: no files found matching '*.txt' 08:24:18 INFO - no previously-included directories found matching 'build' 08:24:18 INFO - no previously-included directories found matching 'dist' 08:24:18 INFO - no previously-included directories found matching '.git*' 08:24:18 INFO - Successfully installed jsonschema functools32 08:24:18 INFO - Cleaning up... 08:24:18 INFO - Return code: 0 08:24:18 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:24:18 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')]} 08:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:18 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')]} 08:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:18 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 0x7f0f2942bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ea8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14b80e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, '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': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', '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 08:24:18 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 08:24:18 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 08:24:18 INFO - Using env: {'DISPLAY': ':0', 08:24:18 INFO - 'HOME': '/home/cltbld', 08:24:18 INFO - 'LANG': 'en_US.UTF-8', 08:24:18 INFO - 'LANGUAGE': 'en_US:en', 08:24:18 INFO - 'LOGNAME': 'cltbld', 08:24:18 INFO - 'MAIL': '/var/mail/cltbld', 08:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:18 INFO - 'MOZ_NO_REMOTE': '1', 08:24:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:18 INFO - 'NO_EM_RESTART': '1', 08:24:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:18 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:18 INFO - 'SHELL': '/bin/bash', 08:24:18 INFO - 'SHLVL': '1', 08:24:18 INFO - 'TERM': 'linux', 08:24:18 INFO - 'TMOUT': '86400', 08:24:18 INFO - 'USER': 'cltbld', 08:24:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:18 INFO - '_': '/tools/buildbot/bin/python'} 08:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:18 INFO - Downloading/unpacking flake8 08:24:18 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 08:24:18 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 08:24:18 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 08:24:18 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 08:24:18 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 08:24:18 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 08:24:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:24:20 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 08:24:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:24:20 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 08:24:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24:20 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 08:24:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24: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 08:24:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 08:24:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:24:20 INFO - no previously-included directories found matching 'docs/_build' 08:24:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:24:20 INFO - Running setup.py install for pep8 08:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:24:20 INFO - no previously-included directories found matching 'docs/_build' 08:24:20 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:24:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:24:20 INFO - Cleaning up... 08:24:21 INFO - Return code: 0 08:24:21 INFO - Running post-action listener: _resource_record_post_action 08:24:21 INFO - Running post-action listener: _start_resource_monitoring 08:24:21 INFO - Starting resource monitoring. 08:24:21 INFO - ##### 08:24:21 INFO - ##### Running install step. 08:24:21 INFO - ##### 08:24:21 INFO - Running pre-action listener: _resource_record_pre_action 08:24:21 INFO - Running main action method: install 08:24:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:24:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:24:21 INFO - Reading from file tmpfile_stdout 08:24:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:24:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:24:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:24:21 INFO - Reading from file tmpfile_stdout 08:24:21 INFO - Output received: 08:24:21 INFO - Usage: mozinstall [options] installer 08:24:21 INFO - Options: 08:24:21 INFO - -h, --help show this help message and exit 08:24:21 INFO - -d DEST, --destination=DEST 08:24:21 INFO - Directory to install application into. [default: 08:24:21 INFO - "/builds/slave/test-pgo"] 08:24:21 INFO - --app=APP Application being installed. [default: firefox] 08:24:21 INFO - mkdir: /builds/slave/test-pgo/build/application 08:24:21 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'] 08:24:21 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 08:24:35 INFO - Reading from file tmpfile_stdout 08:24:35 INFO - Output received: 08:24:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:24:35 INFO - Running post-action listener: _resource_record_post_action 08:24:35 INFO - ##### 08:24:35 INFO - ##### Running run-tests step. 08:24:35 INFO - ##### 08:24:35 INFO - Running pre-action listener: _resource_record_pre_action 08:24:35 INFO - Running main action method: run_tests 08:24:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:24:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:24:35 INFO - Python 2.7.3 08:24:35 INFO - Return code: 0 08:24:35 INFO - grabbing minidump binary from tooltool 08:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:24:35 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 0x1546e30>, '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 0x1547d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15481c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:24:35 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 08:24:35 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 08:24:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:24:35 INFO - Return code: 0 08:24:35 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:24:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:24:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:24:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:24:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:24:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:24:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:24:36 INFO - Return code: 0 08:24:36 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/1452520893/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:24:36 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/1452520893/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost 08:24:36 INFO - Using env: {'DISPLAY': ':0', 08:24:36 INFO - 'HOME': '/home/cltbld', 08:24:36 INFO - 'LANG': 'en_US.UTF-8', 08:24:36 INFO - 'LANGUAGE': 'en_US:en', 08:24:36 INFO - 'LOGNAME': 'cltbld', 08:24:36 INFO - 'MAIL': '/var/mail/cltbld', 08:24:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:24:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:36 INFO - 'MOZ_NO_REMOTE': '1', 08:24:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:24:36 INFO - 'NO_EM_RESTART': '1', 08:24:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:24:36 INFO - 'PWD': '/builds/slave/test-pgo', 08:24:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:24:36 INFO - 'SHELL': '/bin/bash', 08:24:36 INFO - 'SHLVL': '1', 08:24:36 INFO - 'TERM': 'linux', 08:24:36 INFO - 'TMOUT': '86400', 08:24:36 INFO - 'USER': 'cltbld', 08:24:36 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158', 08:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:36 INFO - '_': '/tools/buildbot/bin/python'} 08:24:36 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/1452520893/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] with output_timeout 3600 08:24:36 INFO - mozversion INFO | application_buildid: 20160111060133 08:24:36 INFO - mozversion INFO | application_changeset: 51f069f6fe912bdfa28604a94e047f0bcf467da7 08:24:36 INFO - mozversion INFO | application_display_name: Nightly 08:24:36 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:24:36 INFO - mozversion INFO | application_name: Firefox 08:24:36 INFO - mozversion INFO | application_remotingname: firefox 08:24:36 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:24:36 INFO - mozversion INFO | application_vendor: Mozilla 08:24:36 INFO - mozversion INFO | application_version: 44.0 08:24:36 INFO - mozversion INFO | platform_buildid: 20160111060133 08:24:36 INFO - mozversion INFO | platform_changeset: 51f069f6fe912bdfa28604a94e047f0bcf467da7 08:24:36 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:24:36 INFO - mozversion INFO | platform_version: 44.0 08:24:36 INFO - 2016-01-11 08:24:36,737 DEBUG : using testdate: 1452529476 08:24:36 INFO - 2016-01-11 08:24:36,737 DEBUG : actual date: 1452529476 08:24:36 INFO - 2016-01-11 08:24:36,748 INFO : starting webserver on 'localhost:55334' 08:24:36 INFO - 2016-01-11 08:24:36,750 INFO : Starting test suite talos-linux64-ix-017 08:24:36 INFO - 2016-01-11 08:24:36,750 INFO : Starting test dromaeo_css 08:24:36 INFO - 2016-01-11 08:24:36,750 DEBUG : operating with platform_type : linux_ 08:24:36 INFO - 2016-01-11 08:24:36,750 INFO : Initialising browser for dromaeo_css test... 08:24:36 INFO - 2016-01-11 08:24:36,763 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSAmUdw/profile http://localhost:55334/getInfo.html 08:24:40 INFO - 2016-01-11 08:24:40,082 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:24:40 INFO - 2016-01-11 08:24:40,082 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:24:40 INFO - 2016-01-11 08:24:40,082 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:24:40 INFO - 2016-01-11 08:24:40,083 DEBUG : BROWSER_OUTPUT: __metrics 08:24:40 INFO - 2016-01-11 08:24:40,240 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] 08:24:41 INFO - 2016-01-11 08:24:41,325 INFO : Browser initialized. 08:24:41 INFO - 2016-01-11 08:24:41,326 INFO : Running cycle 1/1 for dromaeo_css test... 08:24:41 INFO - 2016-01-11 08:24:41,327 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpSAmUdw/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:24:52 INFO - 2016-01-11 08:24:52,779 DEBUG : BROWSER_OUTPUT: RSS: Main: 129089536 08:24:52 INFO - 2016-01-11 08:24:52,779 DEBUG : BROWSER_OUTPUT: 08:25:43 INFO - 2016-01-11 08:25:43,755 DEBUG : BROWSER_OUTPUT: 1452529543748 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] 08:27:51 INFO - 2016-01-11 08:27:51,768 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55334/tests/dromaeo/cssquery-ext.html) 08:27:52 INFO - 2016-01-11 08:27:52,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 241102848 08:27:52 INFO - 2016-01-11 08:27:52,894 DEBUG : BROWSER_OUTPUT: 08:30:55 INFO - 2016-01-11 08:30:55,030 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-ext.html (next: http://localhost:55334/tests/dromaeo/cssquery-jquery.html) 08:30:55 INFO - 2016-01-11 08:30:55,592 DEBUG : BROWSER_OUTPUT: RSS: Main: 207515648 08:30:55 INFO - 2016-01-11 08:30:55,592 DEBUG : BROWSER_OUTPUT: 08:33:57 INFO - 2016-01-11 08:33:57,291 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55334/tests/dromaeo/cssquery-mootools.html) 08:33:57 INFO - 2016-01-11 08:33:57,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 209108992 08:33:57 INFO - 2016-01-11 08:33:57,853 DEBUG : BROWSER_OUTPUT: 08:36:59 INFO - 2016-01-11 08:36:59,566 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55334/tests/dromaeo/cssquery-prototype.html) 08:37:00 INFO - 2016-01-11 08:37:00,132 DEBUG : BROWSER_OUTPUT: RSS: Main: 191279104 08:37:00 INFO - 2016-01-11 08:37:00,132 DEBUG : BROWSER_OUTPUT: 08:40:02 INFO - 2016-01-11 08:40:02,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55334/tests/dromaeo/cssquery-yui.html) 08:40:03 INFO - 2016-01-11 08:40:03,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 194695168 08:40:03 INFO - 2016-01-11 08:40:03,084 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,140 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/dromaeo/cssquery-yui.html (next: http://localhost:55334/tests/dromaeo/cssquery-dojo.html) 08:43:05 INFO - 2016-01-11 08:43:05,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 239693824 08:43:05 INFO - 2016-01-11 08:43:05,390 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,391 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:43:05 INFO - 2016-01-11 08:43:05,391 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:43:05 INFO - 2016-01-11 08:43:05,391 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:43:05 INFO - 2016-01-11 08:43:05,391 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:43:05 INFO - 2016-01-11 08:43:05,393 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3294.094124115868;3407.693307602315;3410.403179443598;3418.9658103418965;3445.1042728890498;5629.35262444819;5686.829395118148;5699.430056994299;5712.857178570538;5725.227094342268;5696.430356964302;5734.111212762028;5749.913751293734;5754.568407369454;5754.769809207637;4998.850034498971;5196.3764348278255;5229.320188375525;5297.046531624306;5316.202569614569;5093.668911520739;5197.454267301936;5205.479452054802;5234.816781412651;5267.868303292429;5354.544863686591;5354.598405119612;5375.838724838261;5376.301080859483;5386.030514507387;4953.504649535053;5267.657602255879;5268.341457317835;5332.466753324656;5341.7596208170535;5103.897922041576;5140.357455318085;5189.948100518985;5193.740312984373;5226.2944502492055;5532.031894418461;5542.307211598551;5546.195801608785;5562.193481945095;5564.972175139139;5840.299164100293;5952.434518720758;6191.783287584913;6200.224971878515;6202.875942481171;5981.252343457068;6048.7688214367445;6079.969600151971;6083.8972124216525;6091.634501929899;5766.971165144148;5768.567357448124;5774.249347584787;5776.364599894008;5795.652260864319;6062.060380641008;6131.325554189036;6188.257409110845;6253.343398943045;6259.311475737665;6054.364291749391;6086.056661217518;6117.418845209698;6118.908216376669;6120.908186377208;6181.443670069715;6204.906926396107;6218.22272215973;6222.62664240147;6228.532860035515;5974.372690867396;5995.760169593254;6104.664243466563;6124.11200375943;6129.570930034944;6116.021436570217;6121.265448146251;6125.264968203844;6126.754929802758;6132.41742034506;4691.319758634979;4695.131400690883;4696.201645720235;4702.764861756898;4718.693284936468;4063.4920634920636;4121.91756164875;4153.460050193456;4182.895427614273;4209.684273679425;5748.367679555246;5756.913646295309;5758.165066065397;5762.308522977186;5793.449622285961;5886.970565147232;6057.909131363033;6101.7559297627595;6103.481204097613;6118.816435506853;5708.029634962067;5719.170720245625;5781.132830075522;5798.768049277982;5825.300963884361;5262.92105618416;5349.465053494697;5386.649867758583;5388.515033646991;5392.137258038695;5975.163477113208;6112.419320164578;6358.713857876407;6364.745410183541;6415.230172379344;6061.302950160788;6153.169322141453;6156.322804491592;6169.753209871555;6186.288576813544;6248.593841400295;6299.338569450142;6303.810885673253;6307.558470906993;6308.432241098323;5797.8550536236935;5865.648061116347;5906.881862362815;6057.939420605914;6060.787872424444;6035.034394496859;6077.24034495688;6080.543959203166;6085.1785009023215;6093.542984276109;5942.970285148546;5946.672932988728;5949.405059494189;5949.732262048132;5950.702464876826;5814.709264536672;5918.378570250234;5941.881162376815;5961.105834124916;6085.421884920926;5957.582969192288;5967.403259674172;5973.701314934323;5977.282726072899;5984.042553191469;6053.213082299273;6075.149479072845;6077.4226448487325;6081;6082.148499210026;5675.659460432387;5777.6822274775295;5789.8263052108505;5851.239338885917;5886.58793884424 08:43:05 INFO - 2016-01-11 08:43:05,394 DEBUG : BROWSER_OUTPUT: |1;ext.html;599.5653151465189;647.142536139615;649.3214590752661;659.9241087274964;660.7621256347715;36599.63400365996;44374;44453.77773111138;44462.11075778482;44494.33258501121;47861.760691196585;47981.280280795814;48208.75895620526;48341.51658483414;48474.75762621182;8250.133735957697;8405.537695426749;9312.441253524794;9438.575264113131;10659.413732244724;11005.944970275137;11025.393603351813;11031.669049928474;11123.888761112366;11172.720681982933;10984.945075274612;11045.779084418307;11072.335659860393;11156.888431115665;11173;11020.559177632926;11117.666470005914;11119.610813621566;11121.443927803659;11129.944350278196;10923.945380273128;11054.668359949215;11067;11067.72330691731;11067.778644427066;45861.77069114667;46119.53880461186;46201.76899115483;46290.76854615705;46320.7683961578;3674.963250367489;3808.257389808988;3820.178661587772;3851.0564911596557;4227.429297044921;34582.65417345819;34684.82657586721;34975;35056.64943350559;35234.823825880965;3322.1196382958537;3379.847906844198;3405.829708514589;3418.025862629139;3420.504026916083;366930;371749;372566.99999999726;372568;374595;64267.357326427074;68109.31890681128;68172.659136704;77519.61240193876;77683.2231677676;71327;71498.9275160873;71622.64188679127;71701.64149179221;71921.6403917977;18540.721889171673;18550.814491855177;18627.720584191247;18703.532411689815;18709.812901870806;14958.177300248373;15004.774928376082;15051.924740376227;15054.849451505565;15095.773563396559;23003.424914377276;23121.768782312298;23187.188448404224;23322.300330989758;23384.883075584854;8710.172533609297;8823.647054117764;8927.94692193173;8962.641494340283;8979.343318144878;954.5895265035962;1034.182995433599;1134.5972179876026;1139.9398559339893;1161.361251311775;31038.534421983688;31419.84290078535;35034.474482882775;35331;36907.4463883042;1039.3036665434176;1096.7387504369879;1101.6199411203122;1148.0643275730254;1200.1958687679275;14380.280985950661;15671.921640391725;16083.758743618855;16398.09810460436;16773.329066837436;466091;472863;472873;473631;474103;63830.36169638337;64110.679446602466;64111.35888641147;64613.35386646167;64674;1432.0691550492263;1439.4314245872822;1537.2621141851973;1538.6768778556404;1680.6974744545855;32742.52658630415;33096.50355244721;33248.335033299685;33658.158546036546;35348.11629709175;56500.434995651165;57026.714866425405;57036.71481642565;57065.42934570601;57070.42929570651;521471;522210;523085;523333;528080;46687.066258673956;46737.29894051524;46942.53057469382;47269.2909606363;49204.75397622989;50985.74507127589;51006;51731.482685174175;52515;52534.47465525296;42620.5737942629;42660.360094597985;42853.78573107115;44338.556614433444;47221;41610.58389416067;42826.35760463658;43179.568204317555;43292.78353608212;43922.78038609787;42361.36457953072;42687.786561067;42841.35737963995;47053;47618.52381476141;26479.60280595754;26519;26557.468850622492;26736.732632673426;26900;27997.300067498476;28048.181777607682;28098.9186984586;28408.05146378884;28455.430891381642 08:43:05 INFO - 2016-01-11 08:43:05,398 DEBUG : BROWSER_OUTPUT: |2;jquery.html;393.08999665773734;394.1466724541368;397.95821438748914;398.9182217645383;402.78048463587345;14727.48453804117;18776.4367068988;20340.69488957665;20429.59140817183;21522.139114435402;19060.237590496363;19077.236910523563;19376.903115484438;19401.320953766623;19410.805891941076;5758.769649214037;5859.6777177255235;5885.234919460464;5890.2931648202275;5898.941010589882;5699.715014249292;5844.211031510734;5844.532437404998;5850.590458667895;5893;4427;4531.410916580857;5883.323417806964;5902.675352855591;5903.675297858616;6384.361563843625;6539.967300163468;6577.440917522016;6596.736130554796;6602.768903088393;5748.42515748426;5833.32916714579;5833.883322333572;5837.124431335291;5851.356350801409;24271.514569708685;24282;24585.38536536583;24752.25743227706;24877.62683559748;2308.734495533002;2357.5638506876285;2368.8460250083863;2413.710354757423;2505.9373515662073;14851.925740371229;15022.474213402478;15079.170645614488;15085.54743357701;15287.159206243652;1985.1265443205089;2034.2778313698573;2039.0518408939765;2050.507878109243;2057.3930690446355;69600.3039969604;70473.29526704719;70644;71680.64159679168;72653;11689.473973671205;11984.221025633306;12128.02975761937;12153.939230303791;12154.270743755404;16221.513354599381;16276.348946042026;16279.755803662954;16292.103934283492;16306.26621801998;6400;6564.67176641166;6597.505187110986;6625.635590042498;6678.098456708282;6986.650667466606;6993.160820701549;7032.578045317297;7035.753748618774;7040.2607726189035;3676.227992121631;3734.8879533614036;3736.4021756518828;3760.2291530236234;3761.962380376161;1269.4864168945355;1294.0359432223054;1312.0749871340681;1330.8868746156686;1336.465413834474;520.5965376832909;536.7235873525137;544.5371434280829;549.4917201588514;569.4447913284514;12610.243385396905;13212.603621891358;13254.53609123676;13266.40301186449;13318.134321269086;521.640068352843;572.4876235769046;574.7586013874183;582.790195529609;589.4635881347953;6644.634545099969;6854.794356169323;6927.515073944876;7145.214026457085;7175.174854891754;609316;614853;616407.6718465695;620267;624951;86619;86840;88545;88778;89736.1671491351;558.8742532930139;564.7011872692872;566.5241197394126;566.8809549994464;567.8750674851526;10813.62152324665;11436.199466037298;11494.885051149717;11516;11573.016293615137;40218;40229;41168;41205.381919270636;41244;52791.73604131955;53678.463215367345;55105.1734223995;55125;55185.72407137939;9552.37909535878;9648.421094734338;9659.372140810825;9750.512474376395;9752.975937526458;13009.804852927024;13520.797188042385;13624.795628065785;13774.65563360924;13796.931015344859;11835.34905580167;11893.108016898594;11928.986036186674;12087.576934807239;12127.939360303495;11608.24546404481;11612.419379030845;11633.767324653629;11687.707807304885;11886.405679716152;10476.842847357448;10686.679399618024;11948.880511195124;12198.451069702061;12374.690632734253;9217.861732074158;9328.486933218737;9413.435193888388;9445.858312125461;9449.763755906157;11748.118891083031;11880.168388212742;11889.405529723652;11955.940220298842;12009.759804804029 08:43:05 INFO - 2016-01-11 08:43:05,398 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1538.892277540572;1577.337518242338;1630.1115891838947;1771.41543290714;1916.1951980168328;7265;7485.887711684315;8121.715739949103;8209.712660056906;8224.91775082249;8233.259006689397;8323.042850072228;8399.706010289641;8412.747617571467;8428.831423371528;2424.151546958564;2472.950540989179;2473.826832121752;2474.505098980202;2474.950500989979;2052.363767232155;2096.056774451495;2331.80179684727;2381.8094552435764;2461.4953934443397;2377.3699969508007;2388.3909603051197;2393.3298676370646;2394.7126344838643;2407.458321877581;2433.1848830641757;2453.50929814037;2455.508898220363;2456.496418234258;2458.7418321076207;2193.5503221839485;2212.8008479236946;2214.5903007943584;2215.268961242786;2216.767239439868;8329.250367466897;8383.245507904317;8402.663893444254;8409.915900840975;8413.242808147235;5169.715665638389;5253.212018197301;5253.921191182097;5256.211568264753;5256.474352564751;3306.9899102859185;3325.068980685412;3420.4869269609508;3429.5027221053056;3454.3954807908517;1091.7378236898155;1111.4942701071075;1123.3709122891207;1129.2095533126762;1145;11489.65531034062;11507.424628768527;11545.595904143398;11595.246308989888;11608.30350178992;4741.715497070187;4758.42898852133;4771.832985845479;4779.187538118528;4779.713217206979;9892.752681183028;9973.900260997443;9983.151432128205;9995.400275983464;9996.450195239331;5163.66436181647;5193.169092945111;5202.4277329493725;5203.037438073969;5205.401378841406;4667.509911459316;4676.041411510632;4680.906381872343;4685.390899183152;4687.015726697364;3562.1628917204434;3968.6229808168177;4000.4599379084034;4028.67770578353;4064.9390259146135;1221.059783966341;1289;1293.0948336164722;1298.448159532195;1300.14840279617;1282.6152154353656;1462.795208670787;1481.3259966715118;1485.9182744948916;1506.126446660956;8012;8021.2379823916635;8105.554194519358;8128.9187108129345;8131.471454355448;772.7102336623766;789.455275859655;793.2028311546859;798.3892322373389;905.5608030105394;6272.372762723782;6389.456896163878;6415.486761059104;6423.164988551458;6442.774502892376;9764.414135151914;10010.14913732341;10022.699319020441;10033.598656053822;10049.799004019878;9085.863712044193;9098.86351704725;9177.678781242757;9224.30817688676;9237.630494780267;7702.422318326148;7830.412719046094;7831.530108193527;7833.138354780969;7837.921620783719;7733.149353571215;7790.065192176975;7821.217878212361;7845.9215407845195;8073.717419890276;8843.955780221058;9097.13577210164;9116.225120864801;9126.634934602542;9130.360874738704;9088.909110908806;9266.305027123128;9268.814623707623;9279.164875160997;9291.396059256127;8004.519728816057;8054.395920305883;8081.4747041442115;8081.878771818545;8084.110747817853;7353.558786472829;7547.50941188821;7975.7607271781935;8226.341892648454;8246;7676.923230767621;7687.423443241891;7793.181715919747;7793.454458187873;7872.1340652529325;7638.197989211275;7678.923210768045;7726.845463090819;7730.652120654471;7733.729319474017;7724.034495688039;7882.842343153219;7916.366690664848;7920.485168464032;7924.84150317002;7239.710411583477;7248.202697703354;7282.781516554512;7284.562926224444;7290.599017054113;7146.428285737233;7184.994100825784;7199.856002879808;7319.560826350436;7324.743633972786 08:43:05 INFO - 2016-01-11 08:43:05,401 DEBUG : BROWSER_OUTPUT: |4;prototype.html;377.55448570686593;378.3586820339524;379.0485880440095;383.58764328347024;390.026466081627;5863.882722345551;5971.0744834550605;6058.787942422016;6065.757369705212;6081.9999999999945;5775.133729940511;5855.209546711203;5877.41225877412;5885.646861188332;5902.763889444417;4648.907021859561;4660.510646382132;4661.44062712475;4665.83669571565;4670.7664616769025;4514.8194072237075;4588.472325682539;4616.746078965655;4651.7441540715245;4670.883227919295;4638.55933686301;4650.4186976627925;4655.44134703836;4666.92660688042;4673.696209746373;4625.444946606395;4630.536946305376;4638.652101092414;4648.9767551162195;4661.370714953472;4138.317177665682;4142.420061191436;4146.3365861462025;4146.543880173179;4152.750834949882;5523.28197334348;5628.043232650419;5638.633488823213;5639;5718.857028574277;5472.179173124063;5475.945240547583;5478.369987451454;5480.588955828329;5480.91778623321;6355.904661430082;6357.523185761041;6362.427381535637;6366.458850997671;6368.235811702625;4482.775861206959;4541.682082254243;4582.404287442611;4630.791614377327;4634.582887540138;6404.967975160141;6445.806625801233;6465.418112369909;6467.967660161716;6475.125858009203;4730.881727956828;4736.171170045297;4738.099761045391;4740.5733483986605;4742.264948932921;4727.125481785881;4799.856004319876;4806.182948898696;4807.351007613997;4856.417229932431;5485.369182543979;5486.862828429321;5488.533474654699;5489.698066606375;5489.945100549024;6367.204099487564;6382.425581697669;6383.616982981036;6385.425311721968;6388.680565971776;1741.2599645151013;1751.9562010949574;1752.2202619834052;1752.3516298969207;1757.912104394775;1194.378922960056;1217.829503869459;1220.3959040274942;1232.06363163996;1256.0140289872588;6070.271567411851;6106.542009349317;6111.908321375183;6116.35778243278;6125.601835880653;6464.644444555594;6465.806025819233;6475.643839588868;6477.902831457532;6478.643674597943;5904.996150654399;5963.314218864886;5989.970050149721;6001.459868611759;6008.098785182257;3613.33153366628;3634.109643137448;3637.4543818427446;3638.381475149231;3641.8725344612812;6692.464602831762;6743.15710536183;6745.9662701686175;6748.797536073926;6749.493787965922;6601.603903765789;6685.732570697215;6706.83232919181;6707.195136583544;6717.361850624183;6678.7328506859185;6720.798376048726;6735.4274886635185;6736.326367363225;6739.359760822714;6356.491480681442;6445.645489497935;6554.377334153248;6557.377049180321;6560.901586476111;6339.429451349401;6396.584222025554;6455.225372955276;6457.580257283262;6459.257185423737;6649.567778094409;6688.230853451915;6699.229588597179;6703.731850725916;6704.832379190364;4700.506446823034;4731.313959475926;4767.547083027107;4769;4773.260144677581;6526.804195874131;6529.575577587441;6540.607563546202;6624.436922861611;6633.734650613921;6432.935670643234;6444.420002199825;6448.806535803933;6451.290358060516;6452.128962590178;6314.084457753692;6433.356664333529;6437.710303036468;6448.613083214835;6449.1616089909885;6328.936710632835;6339.651319177489;6344.1118243445035;6349.396807303298;6350.714217860115;6463.289038205699;6469.288378278292;6471.935280647133;6473.579217350857;6476.31998640136;6173.074038894202;6255.655938923403;6276.121343012073;6326.430621243926;6335.3981371769605 08:43:05 INFO - 2016-01-11 08:43:05,401 DEBUG : BROWSER_OUTPUT: |5;yui.html;940.0881145289069;943.71688493452;953.2707478778735;963.0706368354537;970.9563069661865;15104.093754374731;15332.92333538332;15472.149031803247;16118.7582186267;16225.756613650805;14910.329035193416;15020.474283400083;15158.469453569127;15159.545213643582;15175.848241517582;771.9035120609924;782.6830133795822;896.4038914122101;897.9942464439837;920.1258804136064;920.5075284722675;924.2098006204666;930.618446436961;931.0689310689311;931.1154403316842;806.5040000399741;953.3183773601874;961.2838435365662;963.8554216867456;964.7009427077628;812.4597142899964;938.7512309238058;957.2581249531634;957.353786194323;958.1472489484364;708.6917191021922;710.9502334836563;711.274500009992;712.4087007783579;712.918014428337;14824.555263342118;14861.851381486264;14947.850521494755;14958;14958.62603434912;12950.48198072076;13227.404766785517;13348.2658453785;13489.662758431019;13814.171149731048;10504.474776261157;10522.947385263102;10570.048695617354;10600.681979540626;10665.52005159762;3575.803330816804;3622.7645203061716;3645.963540364589;3656.177360093984;3667.504886840269;54073;54305.45694543083;55896.44103558953;56033.71983140099;56104.71947640236;7934.365250779923;7978.321842643324;7984.081830589556;8187.83624327522;10875.347479151276;14031;14032.508862189774;14074.577762667137;14141.434342626386;14183.14901105937;7060.682269297894;7113.181984071898;7138.464615153885;7197.676104575306;7202.171750248788;4499.977500112544;4538.841140560131;4541.704789188692;4560.5895469407915;4636.744979026186;3249.2526718854324;3278.196841773733;3282.573265475473;3283.047916104303;3284.704376606117;480.159720489144;495.6530428699925;499.3408700515285;502.6757741256942;506.0183244505647;629.625372903122;657.8158115727604;657.9046038324416;679.6024325769381;679.9048133261348;8791.5164665944;9120.543972801466;9212.861807072899;9329.626814927327;9330.860037099448;520.2898044169681;635.31957971166;646.7316063833456;663.8539521305305;671.8421171024805;4371.388005679208;4487;4493.820247190141;4583.243764778841;4620.19146649335;61661.07508387378;63962.6801865997;64340.678296609156;64440.355596443434;65577.03268901941;55100.72449637726;55594.16608750872;56245.43754562402;56316.71841640765;61389;53437.1984420234;56205;56297;56497.17514124278;57689;9478.668246611336;9584.329096963425;9665.420074795535;9736.659216927374;9869.703908882744;41709.37435938524;41974.58025419707;42153.156936860476;43281.783591083105;43626.56373436225;49736.25395618996;52015.479845201065;53580.46419535755;54231.72884135712;54563;8550.444221125606;8731.388802783744;8788.121187881161;8793.648254069765;9066.320025997944;12401.75196496047;12612.558560450707;12983.610491685264;13663.453461861813;13695;11586.536538538365;12130.636080917586;12757;13079.607611771662;13289.66775830612;11931;11988;12473;12479;12556.497740090294;11473.082153427538;11630;11646.126540509325;11800.97441057079;12774.74450510966;10728.409937453514;10735.302205356938;10921.78156436851;10965.671029869116;11103;10196.592136314464;10212.183025357805;10251.231157663056;10323.896761032294;10387.740306492398 08:43:05 INFO - 2016-01-11 08:43:05,401 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,853 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: __startTimestamp1452530585389__endTimestamp 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,402 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:43:05 INFO - 2016-01-11 08:43:05,404 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:43:05 INFO - 2016-01-11 08:43:05,404 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,404 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5677.64 Median:5865.65 stddev:626.23 (10.7%) stddev-sans-first:599.58 08:43:05 INFO - 2016-01-11 08:43:05,404 DEBUG : BROWSER_OUTPUT: Values: 3294.1 3407.7 3410.4 3419.0 3445.1 5629.4 5686.8 5699.4 5712.9 5725.2 5696.4 5734.1 5749.9 5754.6 5754.8 4998.9 5196.4 5229.3 5297.0 5316.2 5093.7 5197.5 5205.5 5234.8 5267.9 5354.5 5354.6 5375.8 5376.3 5386.0 4953.5 5267.7 5268.3 5332.5 5341.8 5103.9 5140.4 5189.9 5193.7 5226.3 5532.0 5542.3 5546.2 5562.2 5565.0 5840.3 5952.4 6191.8 6200.2 6202.9 5981.3 6048.8 6080.0 6083.9 6091.6 5767.0 5768.6 5774.2 5776.4 5795.7 6062.1 6131.3 6188.3 6253.3 6259.3 6054.4 6086.1 6117.4 6118.9 6120.9 6181.4 6204.9 6218.2 6222.6 6228.5 5974.4 5995.8 6104.7 6124.1 6129.6 6116.0 6121.3 6125.3 6126.8 6132.4 4691.3 4695.1 4696.2 4702.8 4718.7 4063.5 4121.9 4153.5 4182.9 4209.7 5748.4 5756.9 5758.2 5762.3 5793.4 5887.0 6057.9 6101.8 6103.5 6118.8 5708.0 5719.2 5781.1 5798.8 5825.3 5262.9 5349.5 5386.6 5388.5 5392.1 5975.2 6112.4 6358.7 6364.7 6415.2 6061.3 6153.2 6156.3 6169.8 6186.3 6248.6 6299.3 6303.8 6307.6 6308.4 5797.9 5865.6 5906.9 6057.9 6060.8 6035.0 6077.2 6080.5 6085.2 6093.5 5943.0 5946.7 5949.4 5949.7 5950.7 5814.7 5918.4 5941.9 5961.1 6085.4 5957.6 5967.4 5973.7 5977.3 5984.0 6053.2 6075.1 6077.4 6081.0 6082.1 5675.7 5777.7 5789.8 5851.2 5886.6 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:63972.68 Median:373581.50 stddev:121602.44 (32.6%) stddev-sans-first:121850.48 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: Values: 599.6 647.1 649.3 659.9 660.8 36599.6 44374.0 44453.8 44462.1 44494.3 47861.8 47981.3 48208.8 48341.5 48474.8 8250.1 8405.5 9312.4 9438.6 10659.4 11005.9 11025.4 11031.7 11123.9 11172.7 10984.9 11045.8 11072.3 11156.9 11173.0 11020.6 11117.7 11119.6 11121.4 11129.9 10923.9 11054.7 11067.0 11067.7 11067.8 45861.8 46119.5 46201.8 46290.8 46320.8 3675.0 3808.3 3820.2 3851.1 4227.4 34582.7 34684.8 34975.0 35056.6 35234.8 3322.1 3379.8 3405.8 3418.0 3420.5 366930.0 371749.0 372567.0 372568.0 374595.0 64267.4 68109.3 68172.7 77519.6 77683.2 71327.0 71498.9 71622.6 71701.6 71921.6 18540.7 18550.8 18627.7 18703.5 18709.8 14958.2 15004.8 15051.9 15054.8 15095.8 23003.4 23121.8 23187.2 23322.3 23384.9 8710.2 8823.6 8927.9 8962.6 8979.3 954.6 1034.2 1134.6 1139.9 1161.4 31038.5 31419.8 35034.5 35331.0 36907.4 1039.3 1096.7 1101.6 1148.1 1200.2 14380.3 15671.9 16083.8 16398.1 16773.3 466091.0 472863.0 472873.0 473631.0 474103.0 63830.4 64110.7 64111.4 64613.4 64674.0 1432.1 1439.4 1537.3 1538.7 1680.7 32742.5 33096.5 33248.3 33658.2 35348.1 56500.4 57026.7 57036.7 57065.4 57070.4 521471.0 522210.0 523085.0 523333.0 528080.0 46687.1 46737.3 46942.5 47269.3 49204.8 50985.7 51006.0 51731.5 52515.0 52534.5 42620.6 42660.4 42853.8 44338.6 47221.0 41610.6 42826.4 43179.6 43292.8 43922.8 42361.4 42687.8 42841.4 47053.0 47618.5 26479.6 26519.0 26557.5 26736.7 26900.0 27997.3 28048.2 28098.9 28408.1 28455.4 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31762.32 Median:40223.50 stddev:101051.98 (251.2%) stddev-sans-first:101308.01 08:43:05 INFO - 2016-01-11 08:43:05,405 DEBUG : BROWSER_OUTPUT: Values: 393.1 394.1 398.0 398.9 402.8 14727.5 18776.4 20340.7 20429.6 21522.1 19060.2 19077.2 19376.9 19401.3 19410.8 5758.8 5859.7 5885.2 5890.3 5898.9 5699.7 5844.2 5844.5 5850.6 5893.0 4427.0 4531.4 5883.3 5902.7 5903.7 6384.4 6540.0 6577.4 6596.7 6602.8 5748.4 5833.3 5833.9 5837.1 5851.4 24271.5 24282.0 24585.4 24752.3 24877.6 2308.7 2357.6 2368.8 2413.7 2505.9 14851.9 15022.5 15079.2 15085.5 15287.2 1985.1 2034.3 2039.1 2050.5 2057.4 69600.3 70473.3 70644.0 71680.6 72653.0 11689.5 11984.2 12128.0 12153.9 12154.3 16221.5 16276.3 16279.8 16292.1 16306.3 6400.0 6564.7 6597.5 6625.6 6678.1 6986.7 6993.2 7032.6 7035.8 7040.3 3676.2 3734.9 3736.4 3760.2 3762.0 1269.5 1294.0 1312.1 1330.9 1336.5 520.6 536.7 544.5 549.5 569.4 12610.2 13212.6 13254.5 13266.4 13318.1 521.6 572.5 574.8 582.8 589.5 6644.6 6854.8 6927.5 7145.2 7175.2 609316.0 614853.0 616407.7 620267.0 624951.0 86619.0 86840.0 88545.0 88778.0 89736.2 558.9 564.7 566.5 566.9 567.9 10813.6 11436.2 11494.9 11516.0 11573.0 40218.0 40229.0 41168.0 41205.4 41244.0 52791.7 53678.5 55105.2 55125.0 55185.7 9552.4 9648.4 9659.4 9750.5 9753.0 13009.8 13520.8 13624.8 13774.7 13796.9 11835.3 11893.1 11929.0 12087.6 12127.9 11608.2 11612.4 11633.8 11687.7 11886.4 10476.8 10686.7 11948.9 12198.5 12374.7 9217.9 9328.5 9413.4 9445.9 9449.8 11748.1 11880.2 11889.4 11955.9 12009.8 08:43:05 INFO - 2016-01-11 08:43:05,406 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,406 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5910.78 Median:7192.43 stddev:3050.59 (42.4%) stddev-sans-first:3041.44 08:43:05 INFO - 2016-01-11 08:43:05,407 DEBUG : BROWSER_OUTPUT: Values: 1538.9 1577.3 1630.1 1771.4 1916.2 7265.0 7485.9 8121.7 8209.7 8224.9 8233.3 8323.0 8399.7 8412.7 8428.8 2424.2 2473.0 2473.8 2474.5 2475.0 2052.4 2096.1 2331.8 2381.8 2461.5 2377.4 2388.4 2393.3 2394.7 2407.5 2433.2 2453.5 2455.5 2456.5 2458.7 2193.6 2212.8 2214.6 2215.3 2216.8 8329.3 8383.2 8402.7 8409.9 8413.2 5169.7 5253.2 5253.9 5256.2 5256.5 3307.0 3325.1 3420.5 3429.5 3454.4 1091.7 1111.5 1123.4 1129.2 1145.0 11489.7 11507.4 11545.6 11595.2 11608.3 4741.7 4758.4 4771.8 4779.2 4779.7 9892.8 9973.9 9983.2 9995.4 9996.5 5163.7 5193.2 5202.4 5203.0 5205.4 4667.5 4676.0 4680.9 4685.4 4687.0 3562.2 3968.6 4000.5 4028.7 4064.9 1221.1 1289.0 1293.1 1298.4 1300.1 1282.6 1462.8 1481.3 1485.9 1506.1 8012.0 8021.2 8105.6 8128.9 8131.5 772.7 789.5 793.2 798.4 905.6 6272.4 6389.5 6415.5 6423.2 6442.8 9764.4 10010.1 10022.7 10033.6 10049.8 9085.9 9098.9 9177.7 9224.3 9237.6 7702.4 7830.4 7831.5 7833.1 7837.9 7733.1 7790.1 7821.2 7845.9 8073.7 8844.0 9097.1 9116.2 9126.6 9130.4 9088.9 9266.3 9268.8 9279.2 9291.4 8004.5 8054.4 8081.5 8081.9 8084.1 7353.6 7547.5 7975.8 8226.3 8246.0 7676.9 7687.4 7793.2 7793.5 7872.1 7638.2 7678.9 7726.8 7730.7 7733.7 7724.0 7882.8 7916.4 7920.5 7924.8 7239.7 7248.2 7282.8 7284.6 7290.6 7146.4 7185.0 7199.9 7319.6 7324.7 08:43:05 INFO - 2016-01-11 08:43:05,407 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,407 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5362.26 Median:6004.78 stddev:1548.42 (25.8%) stddev-sans-first:1506.89 08:43:05 INFO - 2016-01-11 08:43:05,407 DEBUG : BROWSER_OUTPUT: Values: 377.6 378.4 379.0 383.6 390.0 5863.9 5971.1 6058.8 6065.8 6082.0 5775.1 5855.2 5877.4 5885.6 5902.8 4648.9 4660.5 4661.4 4665.8 4670.8 4514.8 4588.5 4616.7 4651.7 4670.9 4638.6 4650.4 4655.4 4666.9 4673.7 4625.4 4630.5 4638.7 4649.0 4661.4 4138.3 4142.4 4146.3 4146.5 4152.8 5523.3 5628.0 5638.6 5639.0 5718.9 5472.2 5475.9 5478.4 5480.6 5480.9 6355.9 6357.5 6362.4 6366.5 6368.2 4482.8 4541.7 4582.4 4630.8 4634.6 6405.0 6445.8 6465.4 6468.0 6475.1 4730.9 4736.2 4738.1 4740.6 4742.3 4727.1 4799.9 4806.2 4807.4 4856.4 5485.4 5486.9 5488.5 5489.7 5489.9 6367.2 6382.4 6383.6 6385.4 6388.7 1741.3 1752.0 1752.2 1752.4 1757.9 1194.4 1217.8 1220.4 1232.1 1256.0 6070.3 6106.5 6111.9 6116.4 6125.6 6464.6 6465.8 6475.6 6477.9 6478.6 5905.0 5963.3 5990.0 6001.5 6008.1 3613.3 3634.1 3637.5 3638.4 3641.9 6692.5 6743.2 6746.0 6748.8 6749.5 6601.6 6685.7 6706.8 6707.2 6717.4 6678.7 6720.8 6735.4 6736.3 6739.4 6356.5 6445.6 6554.4 6557.4 6560.9 6339.4 6396.6 6455.2 6457.6 6459.3 6649.6 6688.2 6699.2 6703.7 6704.8 4700.5 4731.3 4767.5 4769.0 4773.3 6526.8 6529.6 6540.6 6624.4 6633.7 6432.9 6444.4 6448.8 6451.3 6452.1 6314.1 6433.4 6437.7 6448.6 6449.2 6328.9 6339.7 6344.1 6349.4 6350.7 6463.3 6469.3 6471.9 6473.6 6476.3 6173.1 6255.7 6276.1 6326.4 6335.4 08:43:05 INFO - 2016-01-11 08:43:05,407 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,408 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15240.21 Median:504.35 stddev:18509.89 (3670.1%) stddev-sans-first:18530.67 08:43:05 INFO - 2016-01-11 08:43:05,408 DEBUG : BROWSER_OUTPUT: Values: 940.1 943.7 953.3 963.1 971.0 15104.1 15332.9 15472.1 16118.8 16225.8 14910.3 15020.5 15158.5 15159.5 15175.8 771.9 782.7 896.4 898.0 920.1 920.5 924.2 930.6 931.1 931.1 806.5 953.3 961.3 963.9 964.7 812.5 938.8 957.3 957.4 958.1 708.7 711.0 711.3 712.4 712.9 14824.6 14861.9 14947.9 14958.0 14958.6 12950.5 13227.4 13348.3 13489.7 13814.2 10504.5 10522.9 10570.0 10600.7 10665.5 3575.8 3622.8 3646.0 3656.2 3667.5 54073.0 54305.5 55896.4 56033.7 56104.7 7934.4 7978.3 7984.1 8187.8 10875.3 14031.0 14032.5 14074.6 14141.4 14183.1 7060.7 7113.2 7138.5 7197.7 7202.2 4500.0 4538.8 4541.7 4560.6 4636.7 3249.3 3278.2 3282.6 3283.0 3284.7 480.2 495.7 499.3 502.7 506.0 629.6 657.8 657.9 679.6 679.9 8791.5 9120.5 9212.9 9329.6 9330.9 520.3 635.3 646.7 663.9 671.8 4371.4 4487.0 4493.8 4583.2 4620.2 61661.1 63962.7 64340.7 64440.4 65577.0 55100.7 55594.2 56245.4 56316.7 61389.0 53437.2 56205.0 56297.0 56497.2 57689.0 9478.7 9584.3 9665.4 9736.7 9869.7 41709.4 41974.6 42153.2 43281.8 43626.6 49736.3 52015.5 53580.5 54231.7 54563.0 8550.4 8731.4 8788.1 8793.6 9066.3 12401.8 12612.6 12983.6 13663.5 13695.0 11586.5 12130.6 12757.0 13079.6 13289.7 11931.0 11988.0 12473.0 12479.0 12556.5 11473.1 11630.0 11646.1 11801.0 12774.7 10728.4 10735.3 10921.8 10965.7 11103.0 10196.6 10212.2 10251.2 10323.9 10387.7 08:43:05 INFO - 2016-01-11 08:43:05,408 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:43:05 INFO - 2016-01-11 08:43:05,408 DEBUG : BROWSER_OUTPUT: 08:43:05 INFO - 2016-01-11 08:43:05,957 INFO : Browser exited with error code: 0 08:43:05 INFO - 2016-01-11 08:43:05,973 INFO : Completed test dromaeo_css (00:18:29) 08:43:05 INFO - 2016-01-11 08:43:05,973 INFO : Starting test kraken 08:43:05 INFO - 2016-01-11 08:43:05,974 DEBUG : operating with platform_type : linux_ 08:43:05 INFO - 2016-01-11 08:43:05,974 INFO : Initialising browser for kraken test... 08:43:05 INFO - 2016-01-11 08:43:05,980 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEHC56Y/profile http://localhost:55334/getInfo.html 08:43:09 INFO - 2016-01-11 08:43:09,117 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:43:09 INFO - 2016-01-11 08:43:09,117 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:43:09 INFO - 2016-01-11 08:43:09,117 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:43:09 INFO - 2016-01-11 08:43:09,117 DEBUG : BROWSER_OUTPUT: __metrics 08:43:09 INFO - 2016-01-11 08:43:09,234 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] 08:43:10 INFO - 2016-01-11 08:43:10,257 INFO : Browser initialized. 08:43:10 INFO - 2016-01-11 08:43:10,258 INFO : Running cycle 1/1 for kraken test... 08:43:10 INFO - 2016-01-11 08:43:10,258 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEHC56Y/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:43:21 INFO - 2016-01-11 08:43:21,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 128741376 08:43:21 INFO - 2016-01-11 08:43:21,832 DEBUG : BROWSER_OUTPUT: 08:43:24 INFO - 2016-01-11 08:43:24,240 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55334/tests/kraken/driver.html?testName=audio-beat-detection) 08:43:24 INFO - 2016-01-11 08:43:24,810 DEBUG : BROWSER_OUTPUT: RSS: Main: 163246080 08:43:24 INFO - 2016-01-11 08:43:24,810 DEBUG : BROWSER_OUTPUT: 08:43:27 INFO - 2016-01-11 08:43:27,366 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55334/tests/kraken/driver.html?testName=audio-dft) 08:43:27 INFO - 2016-01-11 08:43:27,932 DEBUG : BROWSER_OUTPUT: RSS: Main: 160317440 08:43:27 INFO - 2016-01-11 08:43:27,932 DEBUG : BROWSER_OUTPUT: 08:43:30 INFO - 2016-01-11 08:43:30,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55334/tests/kraken/driver.html?testName=audio-fft) 08:43:31 INFO - 2016-01-11 08:43:31,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 156872704 08:43:31 INFO - 2016-01-11 08:43:31,260 DEBUG : BROWSER_OUTPUT: 08:43:33 INFO - 2016-01-11 08:43:33,199 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55334/tests/kraken/driver.html?testName=audio-oscillator) 08:43:33 INFO - 2016-01-11 08:43:33,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 156033024 08:43:33 INFO - 2016-01-11 08:43:33,765 DEBUG : BROWSER_OUTPUT: 08:43:35 INFO - 2016-01-11 08:43:35,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55334/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:43:36 INFO - 2016-01-11 08:43:36,426 DEBUG : BROWSER_OUTPUT: RSS: Main: 155766784 08:43:36 INFO - 2016-01-11 08:43:36,427 DEBUG : BROWSER_OUTPUT: 08:43:39 INFO - 2016-01-11 08:43:39,296 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55334/tests/kraken/driver.html?testName=imaging-darkroom) 08:43:39 INFO - 2016-01-11 08:43:39,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 154992640 08:43:39 INFO - 2016-01-11 08:43:39,862 DEBUG : BROWSER_OUTPUT: 08:43:43 INFO - 2016-01-11 08:43:43,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55334/tests/kraken/driver.html?testName=imaging-desaturate) 08:43:43 INFO - 2016-01-11 08:43:43,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 156090368 08:43:43 INFO - 2016-01-11 08:43:43,759 DEBUG : BROWSER_OUTPUT: 08:43:46 INFO - 2016-01-11 08:43:46,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55334/tests/kraken/driver.html?testName=json-parse-financial) 08:43:47 INFO - 2016-01-11 08:43:47,059 DEBUG : BROWSER_OUTPUT: RSS: Main: 156610560 08:43:47 INFO - 2016-01-11 08:43:47,059 DEBUG : BROWSER_OUTPUT: 08:43:48 INFO - 2016-01-11 08:43:48,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55334/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:43:49 INFO - 2016-01-11 08:43:49,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 162127872 08:43:49 INFO - 2016-01-11 08:43:49,137 DEBUG : BROWSER_OUTPUT: 08:43:50 INFO - 2016-01-11 08:43:50,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:43:51 INFO - 2016-01-11 08:43:51,452 DEBUG : BROWSER_OUTPUT: RSS: Main: 158834688 08:43:51 INFO - 2016-01-11 08:43:51,453 DEBUG : BROWSER_OUTPUT: 08:43:53 INFO - 2016-01-11 08:43:53,389 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:43:53 INFO - 2016-01-11 08:43:53,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 163045376 08:43:53 INFO - 2016-01-11 08:43:53,955 DEBUG : BROWSER_OUTPUT: 08:43:56 INFO - 2016-01-11 08:43:56,274 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:43:56 INFO - 2016-01-11 08:43:56,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 167845888 08:43:56 INFO - 2016-01-11 08:43:56,848 DEBUG : BROWSER_OUTPUT: 08:43:59 INFO - 2016-01-11 08:43:59,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:43:59 INFO - 2016-01-11 08:43:59,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 165507072 08:43:59 INFO - 2016-01-11 08:43:59,844 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,472 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55334/tests/kraken/driver.html?testName=ai-astar) 08:44:01 INFO - 2016-01-11 08:44:01,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 225894400 08:44:01 INFO - 2016-01-11 08:44:01,717 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,719 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:44:01 INFO - 2016-01-11 08:44:01,719 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:44:01 INFO - 2016-01-11 08:44:01,719 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:44:01 INFO - 2016-01-11 08:44:01,719 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:44:01 INFO - 2016-01-11 08:44:01,719 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;109;109;108;99;108;99;108 08:44:01 INFO - 2016-01-11 08:44:01,720 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;119;148;163;123;146;162;124;158;121 08:44:01 INFO - 2016-01-11 08:44:01,720 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;158;161;159;159;158;157;160;159;158 08:44:01 INFO - 2016-01-11 08:44:01,720 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;150;72;72;71;72;161;71 08:44:01 INFO - 2016-01-11 08:44:01,720 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;136;88;89;89;130;89;89;134 08:44:01 INFO - 2016-01-11 08:44:01,721 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;110;109;109;110;109 08:44:01 INFO - 2016-01-11 08:44:01,721 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;145;145;144;144;145;145 08:44:01 INFO - 2016-01-11 08:44:01,721 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;95;96;96;96;95 08:44:01 INFO - 2016-01-11 08:44:01,721 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;55;57;54;54;55;54;55;53;53 08:44:01 INFO - 2016-01-11 08:44:01,721 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;64;63;63;64;63;63;65;63 08:44:01 INFO - 2016-01-11 08:44:01,723 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;76;85;79;78;77;78;78;79;77 08:44:01 INFO - 2016-01-11 08:44:01,723 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;116;109;116;117;108;109;109;114;110 08:44:01 INFO - 2016-01-11 08:44:01,723 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;138;140;136;139;141;135;140;133;135 08:44:01 INFO - 2016-01-11 08:44:01,723 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;61;61;62;61;66;61;63;63;60 08:44:01 INFO - 2016-01-11 08:44:01,723 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:44:01 INFO - 2016-01-11 08:44:01,724 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:44:01 INFO - 2016-01-11 08:44:01,724 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,854 08:44:01 INFO - 2016-01-11 08:44:01,724 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:44:01 INFO - 2016-01-11 08:44:01,724 DEBUG : BROWSER_OUTPUT: __startTimestamp1452530641718__endTimestamp 08:44:01 INFO - 2016-01-11 08:44:01,725 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,726 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:44:01 INFO - 2016-01-11 08:44:01,726 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:44:01 INFO - 2016-01-11 08:44:01,727 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,727 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.00 Median:109.00 stddev:4.14 (3.8%) stddev-sans-first:4.37 08:44:01 INFO - 2016-01-11 08:44:01,727 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 109.0 109.0 108.0 99.0 108.0 99.0 108.0 08:44:01 INFO - 2016-01-11 08:44:01,727 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,728 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.50 Median:149.50 stddev:17.90 (12.0%) stddev-sans-first:18.65 08:44:01 INFO - 2016-01-11 08:44:01,728 DEBUG : BROWSER_OUTPUT: Values: 151.0 119.0 148.0 163.0 123.0 146.0 162.0 124.0 158.0 121.0 08:44:01 INFO - 2016-01-11 08:44:01,728 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.00 Median:159.00 stddev:1.33 (0.8%) stddev-sans-first:1.20 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: Values: 161.0 158.0 161.0 159.0 159.0 158.0 157.0 160.0 159.0 158.0 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.40 Median:72.00 stddev:35.46 (49.3%) stddev-sans-first:37.05 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 150.0 72.0 72.0 71.0 72.0 161.0 71.0 08:44:01 INFO - 2016-01-11 08:44:01,729 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,730 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.20 Median:89.00 stddev:21.53 (24.2%) stddev-sans-first:22.30 08:44:01 INFO - 2016-01-11 08:44:01,730 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 136.0 88.0 89.0 89.0 130.0 89.0 89.0 134.0 08:44:01 INFO - 2016-01-11 08:44:01,730 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,730 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:44:01 INFO - 2016-01-11 08:44:01,730 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 08:44:01 INFO - 2016-01-11 08:44:01,731 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,731 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:144.80 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 08:44:01 INFO - 2016-01-11 08:44:01,731 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 145.0 145.0 144.0 144.0 145.0 145.0 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.17 (2.2%) stddev-sans-first:1.24 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: Values: 54.0 55.0 57.0 54.0 54.0 55.0 54.0 55.0 53.0 53.0 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 08:44:01 INFO - 2016-01-11 08:44:01,732 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 64.0 63.0 63.0 64.0 63.0 63.0 65.0 63.0 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:78.50 stddev:3.19 (4.1%) stddev-sans-first:2.60 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: Values: 85.0 76.0 85.0 79.0 78.0 77.0 78.0 78.0 79.0 77.0 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.30 Median:115.00 stddev:5.38 (4.7%) stddev-sans-first:3.67 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: Values: 125.0 116.0 109.0 116.0 117.0 108.0 109.0 109.0 114.0 110.0 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.20 Median:138.50 stddev:2.74 (2.0%) stddev-sans-first:2.79 08:44:01 INFO - 2016-01-11 08:44:01,733 DEBUG : BROWSER_OUTPUT: Values: 135.0 138.0 140.0 136.0 139.0 141.0 135.0 140.0 133.0 135.0 08:44:01 INFO - 2016-01-11 08:44:01,734 DEBUG : BROWSER_OUTPUT: 08:44:01 INFO - 2016-01-11 08:44:01,734 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.50 stddev:1.73 (2.8%) stddev-sans-first:1.80 08:44:01 INFO - 2016-01-11 08:44:01,734 DEBUG : BROWSER_OUTPUT: Values: 63.0 61.0 61.0 62.0 61.0 66.0 61.0 63.0 63.0 60.0 08:44:01 INFO - 2016-01-11 08:44:01,734 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:44:01 INFO - 2016-01-11 08:44:01,734 DEBUG : BROWSER_OUTPUT: 08:44:02 INFO - 2016-01-11 08:44:02,354 INFO : Browser exited with error code: 0 08:44:02 INFO - 2016-01-11 08:44:02,404 INFO : Completed test kraken (00:00:56) 08:44:02 INFO - 2016-01-11 08:44:02,404 INFO : Starting test v8_7 08:44:02 INFO - 2016-01-11 08:44:02,404 DEBUG : operating with platform_type : linux_ 08:44:02 INFO - 2016-01-11 08:44:02,405 INFO : Initialising browser for v8_7 test... 08:44:02 INFO - 2016-01-11 08:44:02,411 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2jI54i/profile http://localhost:55334/getInfo.html 08:44:05 INFO - 2016-01-11 08:44:05,398 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:44:05 INFO - 2016-01-11 08:44:05,398 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:44:05 INFO - 2016-01-11 08:44:05,399 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:44:05 INFO - 2016-01-11 08:44:05,399 DEBUG : BROWSER_OUTPUT: __metrics 08:44:05 INFO - 2016-01-11 08:44:05,535 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] 08:44:06 INFO - 2016-01-11 08:44:06,585 INFO : Browser initialized. 08:44:06 INFO - 2016-01-11 08:44:06,586 INFO : Running cycle 1/1 for v8_7 test... 08:44:06 INFO - 2016-01-11 08:44:06,586 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp2jI54i/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:44:18 INFO - 2016-01-11 08:44:18,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 128225280 08:44:18 INFO - 2016-01-11 08:44:18,094 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,485 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:55334/tests/v8_7/run.html (next: http://localhost:55334/tests/v8_7/run.html) 08:44:39 INFO - 2016-01-11 08:44:39,735 DEBUG : BROWSER_OUTPUT: RSS: Main: 420216832 08:44:39 INFO - 2016-01-11 08:44:39,735 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,735 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:44:39 INFO - 2016-01-11 08:44:39,735 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |0;Richards;163.29196603527106 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.15484090522375 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.09572983533354 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5000 08:44:39 INFO - 2016-01-11 08:44:39,736 DEBUG : BROWSER_OUTPUT: |4;RayTrace;945.179584120983 08:44:39 INFO - 2016-01-11 08:44:39,737 DEBUG : BROWSER_OUTPUT: |5;Earley;553.7098560354374 08:44:39 INFO - 2016-01-11 08:44:39,737 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 08:44:39 INFO - 2016-01-11 08:44:39,737 DEBUG : BROWSER_OUTPUT: |7;RegExp;25769.23076923077 08:44:39 INFO - 2016-01-11 08:44:39,737 DEBUG : BROWSER_OUTPUT: |8;Splay;531.632110579479 08:44:39 INFO - 2016-01-11 08:44:39,737 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 08:44:39 INFO - 2016-01-11 08:44:39,738 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:44:39 INFO - 2016-01-11 08:44:39,738 DEBUG : BROWSER_OUTPUT: __startTimestamp1452530679734__endTimestamp 08:44:39 INFO - 2016-01-11 08:44:39,738 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,738 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:44:39 INFO - 2016-01-11 08:44:39,738 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.29 Median:163.29 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: Values: 163.3 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.15 Median:170.15 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,739 DEBUG : BROWSER_OUTPUT: Values: 170.2 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: Values: 279.1 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5000.00 Median:5000.00 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,740 DEBUG : BROWSER_OUTPUT: Values: 5000.0 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:945.18 Median:945.18 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: Values: 945.2 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.71 Median:553.71 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,741 DEBUG : BROWSER_OUTPUT: Values: 553.7 08:44:39 INFO - 2016-01-11 08:44:39,742 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,743 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,743 DEBUG : BROWSER_OUTPUT: Values: 10437.5 08:44:39 INFO - 2016-01-11 08:44:39,743 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,743 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25769.23 Median:25769.23 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,743 DEBUG : BROWSER_OUTPUT: Values: 25769.2 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:531.63 Median:531.63 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: Values: 531.6 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: Values: 4812.5 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:44:39 INFO - 2016-01-11 08:44:39,744 DEBUG : BROWSER_OUTPUT: 08:44:40 INFO - 2016-01-11 08:44:40,422 INFO : Browser exited with error code: 0 08:44:40 INFO - 2016-01-11 08:44:40,433 INFO : Completed test v8_7 (00:00:38) 08:44:40 INFO - 2016-01-11 08:44:40,771 INFO : Completed test suite (00:20:04) 08:44:40 INFO - 2016-01-11 08:44:40,772 DEBUG : Working with test: dromaeo_css 08:44:40 INFO - 2016-01-11 08:44:40,772 DEBUG : Generating results file: dromaeo_css 08:44:40 INFO - 2016-01-11 08:44:40,773 DEBUG : Working with test: kraken 08:44:40 INFO - 2016-01-11 08:44:40,773 DEBUG : Generating results file: kraken 08:44:40 INFO - 2016-01-11 08:44:40,773 INFO : javascript benchmark 08:44:40 INFO - 2016-01-11 08:44:40,773 DEBUG : Working with test: v8_7 08:44:40 INFO - 2016-01-11 08:44:40,774 DEBUG : Generating results file: v8_7 08:44:40 INFO - 2016-01-11 08:44:40,774 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:44:40 INFO - 2016-01-11 08:44:40,827 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:44:40 INFO - 2016-01-11 08:44:40,827 DEBUG : process_Request line: dromaeo_css 7414.32 graph.html#tests=[[72,53,35]] 08:44:40 INFO - 2016-01-11 08:44:40,827 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:44:40 INFO - 2016-01-11 08:44:40,855 DEBUG : process_Request line: kraken 1456.20 graph.html#tests=[[232,53,35]] 08:44:40 INFO - 2016-01-11 08:44:40,856 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:44:40 INFO - 2016-01-11 08:44:40,885 DEBUG : process_Request line: v8_7 23169.99 graph.html#tests=[[230,53,35]] 08:44:40 INFO - 2016-01-11 08:44:40,887 INFO : javascript benchmark 08:44:40 INFO - 2016-01-11 08:44:40,890 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3294.094124115868, 3407.693307602315, 3410.403179443598, 3418.9658103418965, 3445.1042728890498, 5629.35262444819, 5686.829395118148, 5699.430056994299, 5712.857178570538, 5725.227094342268, 5696.430356964302, 5734.111212762028, 5749.913751293734, 5754.568407369454, 5754.769809207637, 4998.850034498971, 5196.3764348278255, 5229.320188375525, 5297.046531624306, 5316.202569614569, 5093.668911520739, 5197.454267301936, 5205.479452054802, 5234.816781412651, 5267.868303292429, 5354.544863686591, 5354.598405119612, 5375.838724838261, 5376.301080859483, 5386.030514507387, 4953.504649535053, 5267.657602255879, 5268.341457317835, 5332.466753324656, 5341.7596208170535, 5103.897922041576, 5140.357455318085, 5189.948100518985, 5193.740312984373, 5226.2944502492055, 5532.031894418461, 5542.307211598551, 5546.195801608785, 5562.193481945095, 5564.972175139139, 5840.299164100293, 5952.434518720758, 6191.783287584913, 6200.224971878515, 6202.875942481171, 5981.252343457068, 6048.7688214367445, 6079.969600151971, 6083.8972124216525, 6091.634501929899, 5766.971165144148, 5768.567357448124, 5774.249347584787, 5776.364599894008, 5795.652260864319, 6062.060380641008, 6131.325554189036, 6188.257409110845, 6253.343398943045, 6259.311475737665, 6054.364291749391, 6086.056661217518, 6117.418845209698, 6118.908216376669, 6120.908186377208, 6181.443670069715, 6204.906926396107, 6218.22272215973, 6222.62664240147, 6228.532860035515, 5974.372690867396, 5995.760169593254, 6104.664243466563, 6124.11200375943, 6129.570930034944, 6116.021436570217, 6121.265448146251, 6125.264968203844, 6126.754929802758, 6132.41742034506, 4691.319758634979, 4695.131400690883, 4696.201645720235, 4702.764861756898, 4718.693284936468, 4063.4920634920636, 4121.91756164875, 4153.460050193456, 4182.895427614273, 4209.684273679425, 5748.367679555246, 5756.913646295309, 5758.165066065397, 5762.308522977186, 5793.449622285961, 5886.970565147232, 6057.909131363033, 6101.7559297627595, 6103.481204097613, 6118.816435506853, 5708.029634962067, 5719.170720245625, 5781.132830075522, 5798.768049277982, 5825.300963884361, 5262.92105618416, 5349.465053494697, 5386.649867758583, 5388.515033646991, 5392.137258038695, 5975.163477113208, 6112.419320164578, 6358.713857876407, 6364.745410183541, 6415.230172379344, 6061.302950160788, 6153.169322141453, 6156.322804491592, 6169.753209871555, 6186.288576813544, 6248.593841400295, 6299.338569450142, 6303.810885673253, 6307.558470906993, 6308.432241098323, 5797.8550536236935, 5865.648061116347, 5906.881862362815, 6057.939420605914, 6060.787872424444, 6035.034394496859, 6077.24034495688, 6080.543959203166, 6085.1785009023215, 6093.542984276109, 5942.970285148546, 5946.672932988728, 5949.405059494189, 5949.732262048132, 5950.702464876826, 5814.709264536672, 5918.378570250234, 5941.881162376815, 5961.105834124916, 6085.421884920926, 5957.582969192288, 5967.403259674172, 5973.701314934323, 5977.282726072899, 5984.042553191469, 6053.213082299273, 6075.149479072845, 6077.4226448487325, 6081.0, 6082.148499210026, 5675.659460432387, 5777.6822274775295, 5789.8263052108505, 5851.239338885917, 5886.58793884424], "unit": "ms", "name": "dojo.html", "value": 5636.971095354165}, {"lowerIsBetter": false, "replicates": [599.5653151465189, 647.142536139615, 649.3214590752661, 659.9241087274964, 660.7621256347715, 36599.63400365996, 44374.0, 44453.77773111138, 44462.11075778482, 44494.33258501121, 47861.760691196585, 47981.280280795814, 48208.75895620526, 48341.51658483414, 48474.75762621182, 8250.133735957697, 8405.537695426749, 9312.441253524794, 9438.575264113131, 10659.413732244724, 11005.944970275137, 11025.393603351813, 11031.669049928474, 11123.888761112366, 11172.720681982933, 10984.945075274612, 11045.779084418307, 11072.335659860393, 11156.888431115665, 11173.0, 11020.559177632926, 11117.666470005914, 11119.610813621566, 11121.443927803659, 11129.944350278196, 10923.945380273128, 11054.668359949215, 11067.0, 11067.72330691731, 11067.778644427066, 45861.77069114667, 46119.53880461186, 46201.76899115483, 46290.76854615705, 46320.7683961578, 3674.963250367489, 3808.257389808988, 3820.178661587772, 3851.0564911596557, 4227.429297044921, 34582.65417345819, 34684.82657586721, 34975.0, 35056.64943350559, 35234.823825880965, 3322.1196382958537, 3379.847906844198, 3405.829708514589, 3418.025862629139, 3420.504026916083, 366930.0, 371749.0, 372566.99999999726, 372568.0, 374595.0, 64267.357326427074, 68109.31890681128, 68172.659136704, 77519.61240193876, 77683.2231677676, 71327.0, 71498.9275160873, 71622.64188679127, 71701.64149179221, 71921.6403917977, 18540.721889171673, 18550.814491855177, 18627.720584191247, 18703.532411689815, 18709.812901870806, 14958.177300248373, 15004.774928376082, 15051.924740376227, 15054.849451505565, 15095.773563396559, 23003.424914377276, 23121.768782312298, 23187.188448404224, 23322.300330989758, 23384.883075584854, 8710.172533609297, 8823.647054117764, 8927.94692193173, 8962.641494340283, 8979.343318144878, 954.5895265035962, 1034.182995433599, 1134.5972179876026, 1139.9398559339893, 1161.361251311775, 31038.534421983688, 31419.84290078535, 35034.474482882775, 35331.0, 36907.4463883042, 1039.3036665434176, 1096.7387504369879, 1101.6199411203122, 1148.0643275730254, 1200.1958687679275, 14380.280985950661, 15671.921640391725, 16083.758743618855, 16398.09810460436, 16773.329066837436, 466091.0, 472863.0, 472873.0, 473631.0, 474103.0, 63830.36169638337, 64110.679446602466, 64111.35888641147, 64613.35386646167, 64674.0, 1432.0691550492263, 1439.4314245872822, 1537.2621141851973, 1538.6768778556404, 1680.6974744545855, 32742.52658630415, 33096.50355244721, 33248.335033299685, 33658.158546036546, 35348.11629709175, 56500.434995651165, 57026.714866425405, 57036.71481642565, 57065.42934570601, 57070.42929570651, 521471.0, 522210.0, 523085.0, 523333.0, 528080.0, 46687.066258673956, 46737.29894051524, 46942.53057469382, 47269.2909606363, 49204.75397622989, 50985.74507127589, 51006.0, 51731.482685174175, 52515.0, 52534.47465525296, 42620.5737942629, 42660.360094597985, 42853.78573107115, 44338.556614433444, 47221.0, 41610.58389416067, 42826.35760463658, 43179.568204317555, 43292.78353608212, 43922.78038609787, 42361.36457953072, 42687.786561067, 42841.35737963995, 47053.0, 47618.52381476141, 26479.60280595754, 26519.0, 26557.468850622492, 26736.732632673426, 26900.0, 27997.300067498476, 28048.181777607682, 28098.9186984586, 28408.05146378884, 28455.430891381642], "unit": "ms", "name": "ext.html", "value": 21964.315078221665}, {"lowerIsBetter": false, "replicates": [393.08999665773734, 394.1466724541368, 397.95821438748914, 398.9182217645383, 402.78048463587345, 14727.48453804117, 18776.4367068988, 20340.69488957665, 20429.59140817183, 21522.139114435402, 19060.237590496363, 19077.236910523563, 19376.903115484438, 19401.320953766623, 19410.805891941076, 5758.769649214037, 5859.6777177255235, 5885.234919460464, 5890.2931648202275, 5898.941010589882, 5699.715014249292, 5844.211031510734, 5844.532437404998, 5850.590458667895, 5893.0, 4427.0, 4531.410916580857, 5883.323417806964, 5902.675352855591, 5903.675297858616, 6384.361563843625, 6539.967300163468, 6577.440917522016, 6596.736130554796, 6602.768903088393, 5748.42515748426, 5833.32916714579, 5833.883322333572, 5837.124431335291, 5851.356350801409, 24271.514569708685, 24282.0, 24585.38536536583, 24752.25743227706, 24877.62683559748, 2308.734495533002, 2357.5638506876285, 2368.8460250083863, 2413.710354757423, 2505.9373515662073, 14851.925740371229, 15022.474213402478, 15079.170645614488, 15085.54743357701, 15287.159206243652, 1985.1265443205089, 2034.2778313698573, 2039.0518408939765, 2050.507878109243, 2057.3930690446355, 69600.3039969604, 70473.29526704719, 70644.0, 71680.64159679168, 72653.0, 11689.473973671205, 11984.221025633306, 12128.02975761937, 12153.939230303791, 12154.270743755404, 16221.513354599381, 16276.348946042026, 16279.755803662954, 16292.103934283492, 16306.26621801998, 6400.0, 6564.67176641166, 6597.505187110986, 6625.635590042498, 6678.098456708282, 6986.650667466606, 6993.160820701549, 7032.578045317297, 7035.753748618774, 7040.2607726189035, 3676.227992121631, 3734.8879533614036, 3736.4021756518828, 3760.2291530236234, 3761.962380376161, 1269.4864168945355, 1294.0359432223054, 1312.0749871340681, 1330.8868746156686, 1336.465413834474, 520.5965376832909, 536.7235873525137, 544.5371434280829, 549.4917201588514, 569.4447913284514, 12610.243385396905, 13212.603621891358, 13254.53609123676, 13266.40301186449, 13318.134321269086, 521.640068352843, 572.4876235769046, 574.7586013874183, 582.790195529609, 589.4635881347953, 6644.634545099969, 6854.794356169323, 6927.515073944876, 7145.214026457085, 7175.174854891754, 609316.0, 614853.0, 616407.6718465695, 620267.0, 624951.0, 86619.0, 86840.0, 88545.0, 88778.0, 89736.1671491351, 558.8742532930139, 564.7011872692872, 566.5241197394126, 566.8809549994464, 567.8750674851526, 10813.62152324665, 11436.199466037298, 11494.885051149717, 11516.0, 11573.016293615137, 40218.0, 40229.0, 41168.0, 41205.381919270636, 41244.0, 52791.73604131955, 53678.463215367345, 55105.1734223995, 55125.0, 55185.72407137939, 9552.37909535878, 9648.421094734338, 9659.372140810825, 9750.512474376395, 9752.975937526458, 13009.804852927024, 13520.797188042385, 13624.795628065785, 13774.65563360924, 13796.931015344859, 11835.34905580167, 11893.108016898594, 11928.986036186674, 12087.576934807239, 12127.939360303495, 11608.24546404481, 11612.419379030845, 11633.767324653629, 11687.707807304885, 11886.405679716152, 10476.842847357448, 10686.679399618024, 11948.880511195124, 12198.451069702061, 12374.690632734253, 9217.861732074158, 9328.486933218737, 9413.435193888388, 9445.858312125461, 9449.763755906157, 11748.118891083031, 11880.168388212742, 11889.405529723652, 11955.940220298842, 12009.759804804029], "unit": "ms", "name": "jquery.html", "value": 8507.760109269182}, {"lowerIsBetter": false, "replicates": [1538.892277540572, 1577.337518242338, 1630.1115891838947, 1771.41543290714, 1916.1951980168328, 7265.0, 7485.887711684315, 8121.715739949103, 8209.712660056906, 8224.91775082249, 8233.259006689397, 8323.042850072228, 8399.706010289641, 8412.747617571467, 8428.831423371528, 2424.151546958564, 2472.950540989179, 2473.826832121752, 2474.505098980202, 2474.950500989979, 2052.363767232155, 2096.056774451495, 2331.80179684727, 2381.8094552435764, 2461.4953934443397, 2377.3699969508007, 2388.3909603051197, 2393.3298676370646, 2394.7126344838643, 2407.458321877581, 2433.1848830641757, 2453.50929814037, 2455.508898220363, 2456.496418234258, 2458.7418321076207, 2193.5503221839485, 2212.8008479236946, 2214.5903007943584, 2215.268961242786, 2216.767239439868, 8329.250367466897, 8383.245507904317, 8402.663893444254, 8409.915900840975, 8413.242808147235, 5169.715665638389, 5253.212018197301, 5253.921191182097, 5256.211568264753, 5256.474352564751, 3306.9899102859185, 3325.068980685412, 3420.4869269609508, 3429.5027221053056, 3454.3954807908517, 1091.7378236898155, 1111.4942701071075, 1123.3709122891207, 1129.2095533126762, 1145.0, 11489.65531034062, 11507.424628768527, 11545.595904143398, 11595.246308989888, 11608.30350178992, 4741.715497070187, 4758.42898852133, 4771.832985845479, 4779.187538118528, 4779.713217206979, 9892.752681183028, 9973.900260997443, 9983.151432128205, 9995.400275983464, 9996.450195239331, 5163.66436181647, 5193.169092945111, 5202.4277329493725, 5203.037438073969, 5205.401378841406, 4667.509911459316, 4676.041411510632, 4680.906381872343, 4685.390899183152, 4687.015726697364, 3562.1628917204434, 3968.6229808168177, 4000.4599379084034, 4028.67770578353, 4064.9390259146135, 1221.059783966341, 1289.0, 1293.0948336164722, 1298.448159532195, 1300.14840279617, 1282.6152154353656, 1462.795208670787, 1481.3259966715118, 1485.9182744948916, 1506.126446660956, 8012.0, 8021.2379823916635, 8105.554194519358, 8128.9187108129345, 8131.471454355448, 772.7102336623766, 789.455275859655, 793.2028311546859, 798.3892322373389, 905.5608030105394, 6272.372762723782, 6389.456896163878, 6415.486761059104, 6423.164988551458, 6442.774502892376, 9764.414135151914, 10010.14913732341, 10022.699319020441, 10033.598656053822, 10049.799004019878, 9085.863712044193, 9098.86351704725, 9177.678781242757, 9224.30817688676, 9237.630494780267, 7702.422318326148, 7830.412719046094, 7831.530108193527, 7833.138354780969, 7837.921620783719, 7733.149353571215, 7790.065192176975, 7821.217878212361, 7845.9215407845195, 8073.717419890276, 8843.955780221058, 9097.13577210164, 9116.225120864801, 9126.634934602542, 9130.360874738704, 9088.909110908806, 9266.305027123128, 9268.814623707623, 9279.164875160997, 9291.396059256127, 8004.519728816057, 8054.395920305883, 8081.4747041442115, 8081.878771818545, 8084.110747817853, 7353.558786472829, 7547.50941188821, 7975.7607271781935, 8226.341892648454, 8246.0, 7676.923230767621, 7687.423443241891, 7793.181715919747, 7793.454458187873, 7872.1340652529325, 7638.197989211275, 7678.923210768045, 7726.845463090819, 7730.652120654471, 7733.729319474017, 7724.034495688039, 7882.842343153219, 7916.366690664848, 7920.485168464032, 7924.84150317002, 7239.710411583477, 7248.202697703354, 7282.781516554512, 7284.562926224444, 7290.599017054113, 7146.428285737233, 7184.994100825784, 7199.856002879808, 7319.560826350436, 7324.743633972786], "unit": "ms", "name": "mootools.html", "value": 4824.0608596009715}, {"lowerIsBetter": false, "replicates": [377.55448570686593, 378.3586820339524, 379.0485880440095, 383.58764328347024, 390.026466081627, 5863.882722345551, 5971.0744834550605, 6058.787942422016, 6065.757369705212, 6081.9999999999945, 5775.133729940511, 5855.209546711203, 5877.41225877412, 5885.646861188332, 5902.763889444417, 4648.907021859561, 4660.510646382132, 4661.44062712475, 4665.83669571565, 4670.7664616769025, 4514.8194072237075, 4588.472325682539, 4616.746078965655, 4651.7441540715245, 4670.883227919295, 4638.55933686301, 4650.4186976627925, 4655.44134703836, 4666.92660688042, 4673.696209746373, 4625.444946606395, 4630.536946305376, 4638.652101092414, 4648.9767551162195, 4661.370714953472, 4138.317177665682, 4142.420061191436, 4146.3365861462025, 4146.543880173179, 4152.750834949882, 5523.28197334348, 5628.043232650419, 5638.633488823213, 5639.0, 5718.857028574277, 5472.179173124063, 5475.945240547583, 5478.369987451454, 5480.588955828329, 5480.91778623321, 6355.904661430082, 6357.523185761041, 6362.427381535637, 6366.458850997671, 6368.235811702625, 4482.775861206959, 4541.682082254243, 4582.404287442611, 4630.791614377327, 4634.582887540138, 6404.967975160141, 6445.806625801233, 6465.418112369909, 6467.967660161716, 6475.125858009203, 4730.881727956828, 4736.171170045297, 4738.099761045391, 4740.5733483986605, 4742.264948932921, 4727.125481785881, 4799.856004319876, 4806.182948898696, 4807.351007613997, 4856.417229932431, 5485.369182543979, 5486.862828429321, 5488.533474654699, 5489.698066606375, 5489.945100549024, 6367.204099487564, 6382.425581697669, 6383.616982981036, 6385.425311721968, 6388.680565971776, 1741.2599645151013, 1751.9562010949574, 1752.2202619834052, 1752.3516298969207, 1757.912104394775, 1194.378922960056, 1217.829503869459, 1220.3959040274942, 1232.06363163996, 1256.0140289872588, 6070.271567411851, 6106.542009349317, 6111.908321375183, 6116.35778243278, 6125.601835880653, 6464.644444555594, 6465.806025819233, 6475.643839588868, 6477.902831457532, 6478.643674597943, 5904.996150654399, 5963.314218864886, 5989.970050149721, 6001.459868611759, 6008.098785182257, 3613.33153366628, 3634.109643137448, 3637.4543818427446, 3638.381475149231, 3641.8725344612812, 6692.464602831762, 6743.15710536183, 6745.9662701686175, 6748.797536073926, 6749.493787965922, 6601.603903765789, 6685.732570697215, 6706.83232919181, 6707.195136583544, 6717.361850624183, 6678.7328506859185, 6720.798376048726, 6735.4274886635185, 6736.326367363225, 6739.359760822714, 6356.491480681442, 6445.645489497935, 6554.377334153248, 6557.377049180321, 6560.901586476111, 6339.429451349401, 6396.584222025554, 6455.225372955276, 6457.580257283262, 6459.257185423737, 6649.567778094409, 6688.230853451915, 6699.229588597179, 6703.731850725916, 6704.832379190364, 4700.506446823034, 4731.313959475926, 4767.547083027107, 4769.0, 4773.260144677581, 6526.804195874131, 6529.575577587441, 6540.607563546202, 6624.436922861611, 6633.734650613921, 6432.935670643234, 6444.420002199825, 6448.806535803933, 6451.290358060516, 6452.128962590178, 6314.084457753692, 6433.356664333529, 6437.710303036468, 6448.613083214835, 6449.1616089909885, 6328.936710632835, 6339.651319177489, 6344.1118243445035, 6349.396807303298, 6350.714217860115, 6463.289038205699, 6469.288378278292, 6471.935280647133, 6473.579217350857, 6476.31998640136, 6173.074038894202, 6255.655938923403, 6276.121343012073, 6326.430621243926, 6335.3981371769605], "unit": "ms", "name": "prototype.html", "value": 4882.170416341099}, {"lowerIsBetter": false, "replicates": [940.0881145289069, 943.71688493452, 953.2707478778735, 963.0706368354537, 970.9563069661865, 15104.093754374731, 15332.92333538332, 15472.149031803247, 16118.7582186267, 16225.756613650805, 14910.329035193416, 15020.474283400083, 15158.469453569127, 15159.545213643582, 15175.848241517582, 771.9035120609924, 782.6830133795822, 896.4038914122101, 897.9942464439837, 920.1258804136064, 920.5075284722675, 924.2098006204666, 930.618446436961, 931.0689310689311, 931.1154403316842, 806.5040000399741, 953.3183773601874, 961.2838435365662, 963.8554216867456, 964.7009427077628, 812.4597142899964, 938.7512309238058, 957.2581249531634, 957.353786194323, 958.1472489484364, 708.6917191021922, 710.9502334836563, 711.274500009992, 712.4087007783579, 712.918014428337, 14824.555263342118, 14861.851381486264, 14947.850521494755, 14958.0, 14958.62603434912, 12950.48198072076, 13227.404766785517, 13348.2658453785, 13489.662758431019, 13814.171149731048, 10504.474776261157, 10522.947385263102, 10570.048695617354, 10600.681979540626, 10665.52005159762, 3575.803330816804, 3622.7645203061716, 3645.963540364589, 3656.177360093984, 3667.504886840269, 54073.0, 54305.45694543083, 55896.44103558953, 56033.71983140099, 56104.71947640236, 7934.365250779923, 7978.321842643324, 7984.081830589556, 8187.83624327522, 10875.347479151276, 14031.0, 14032.508862189774, 14074.577762667137, 14141.434342626386, 14183.14901105937, 7060.682269297894, 7113.181984071898, 7138.464615153885, 7197.676104575306, 7202.171750248788, 4499.977500112544, 4538.841140560131, 4541.704789188692, 4560.5895469407915, 4636.744979026186, 3249.2526718854324, 3278.196841773733, 3282.573265475473, 3283.047916104303, 3284.704376606117, 480.159720489144, 495.6530428699925, 499.3408700515285, 502.6757741256942, 506.0183244505647, 629.625372903122, 657.8158115727604, 657.9046038324416, 679.6024325769381, 679.9048133261348, 8791.5164665944, 9120.543972801466, 9212.861807072899, 9329.626814927327, 9330.860037099448, 520.2898044169681, 635.31957971166, 646.7316063833456, 663.8539521305305, 671.8421171024805, 4371.388005679208, 4487.0, 4493.820247190141, 4583.243764778841, 4620.19146649335, 61661.07508387378, 63962.6801865997, 64340.678296609156, 64440.355596443434, 65577.03268901941, 55100.72449637726, 55594.16608750872, 56245.43754562402, 56316.71841640765, 61389.0, 53437.1984420234, 56205.0, 56297.0, 56497.17514124278, 57689.0, 9478.668246611336, 9584.329096963425, 9665.420074795535, 9736.659216927374, 9869.703908882744, 41709.37435938524, 41974.58025419707, 42153.156936860476, 43281.783591083105, 43626.56373436225, 49736.25395618996, 52015.479845201065, 53580.46419535755, 54231.72884135712, 54563.0, 8550.444221125606, 8731.388802783744, 8788.121187881161, 8793.648254069765, 9066.320025997944, 12401.75196496047, 12612.558560450707, 12983.610491685264, 13663.453461861813, 13695.0, 11586.536538538365, 12130.636080917586, 12757.0, 13079.607611771662, 13289.66775830612, 11931.0, 11988.0, 12473.0, 12479.0, 12556.497740090294, 11473.082153427538, 11630.0, 11646.126540509325, 11800.97441057079, 12774.74450510966, 10728.409937453514, 10735.302205356938, 10921.78156436851, 10965.671029869116, 11103.0, 10196.592136314464, 10212.183025357805, 10251.231157663056, 10323.896761032294, 10387.740306492398], "unit": "ms", "name": "yui.html", "value": 6695.48130301355}], "name": "dromaeo_css", "value": 7414.3157750352175}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 103.0, 109.0, 109.0, 108.0, 99.0, 108.0, 99.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 106.0}, {"lowerIsBetter": true, "replicates": [151.0, 119.0, 148.0, 163.0, 123.0, 146.0, 162.0, 124.0, 158.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.5}, {"lowerIsBetter": true, "replicates": [161.0, 158.0, 161.0, 159.0, 159.0, 158.0, 157.0, 160.0, 159.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 159.0}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 150.0, 72.0, 72.0, 71.0, 72.0, 161.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.4}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 136.0, 88.0, 89.0, 89.0, 130.0, 89.0, 89.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 102.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 145.0, 144.0, 144.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 144.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 57.0, 54.0, 54.0, 55.0, 54.0, 55.0, 53.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 54.4}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 65.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.4}, {"lowerIsBetter": true, "replicates": [85.0, 76.0, 85.0, 79.0, 78.0, 77.0, 78.0, 78.0, 79.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [125.0, 116.0, 109.0, 116.0, 117.0, 108.0, 109.0, 109.0, 114.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.3}, {"lowerIsBetter": true, "replicates": [135.0, 138.0, 140.0, 136.0, 139.0, 141.0, 135.0, 140.0, 133.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.2}, {"lowerIsBetter": true, "replicates": [63.0, 61.0, 61.0, 62.0, 61.0, 66.0, 61.0, 63.0, 63.0, 60.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.1}], "name": "kraken", "value": 1456.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.29196603527106], "unit": "score", "name": "Richards", "value": 216.18944800000006}, {"lowerIsBetter": false, "replicates": [170.15484090522375], "unit": "score", "name": "DeltaBlue", "value": 388.57548600000007}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5000.0], "unit": "score", "name": "Decrypt", "value": 53.23619999999997}, {"lowerIsBetter": false, "replicates": [945.179584120983], "unit": "score", "name": "RayTrace", "value": 782.908362}, {"lowerIsBetter": false, "replicates": [553.7098560354374], "unit": "score", "name": "Earley", "value": 1203.6321779999998}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [25769.23076923077], "unit": "score", "name": "RegExp", "value": 35.3516567164179}, {"lowerIsBetter": false, "replicates": [531.632110579479], "unit": "score", "name": "Splay", "value": 153.28457100000003}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23169.99369952023}]} 08:44:40 INFO - RETURN: dromaeo_css: 7414.32 08:44:40 INFO - RETURN: kraken: 1456.20 08:44:40 INFO - RETURN: v8_7: 23169.99 08:44:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23169.99"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1456.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7414.32"}}} 08:44:40 INFO - Return code: 0 08:44:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:44:41 INFO - # TBPL SUCCESS # 08:44:41 INFO - Running post-action listener: _resource_record_post_action 08:44:41 INFO - Running post-run listener: _resource_record_post_run 08:44:42 INFO - Total resource usage - Wall time: 1219s; CPU: 12.0%; Read bytes: 327680; Write bytes: 279072768; Read time: 224; Write time: 139120 08:44:42 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 14151680; Read time: 0; Write time: 1396 08:44:42 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 327680; Write bytes: 264921088; Read time: 224; Write time: 137724 08:44:42 INFO - Running post-run listener: _upload_blobber_files 08:44:42 INFO - Blob upload gear active. 08:44:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:44:42 INFO - Copying logs to upload dir... 08:44:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1267.935167 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-01-11 08:44:41.896604) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-11 08:44:41.900126) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009734 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452520893/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-11 08:44:41.953270) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:44:41.953790) ========= 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=0333a256a5e4f052382dd180000001b1-1452529407.60783-116350158 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004714 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-11 08:44:42.004584) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-11 08:44:42.004910) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-11 08:44:42.005241) ========= ========= Total master_lag: 0.20 =========