builder: ash_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1448544512.33 results: success (0) buildid: 20151126040238 builduid: 934e165d416a48f79b856c40ca54b1e4 revision: ffcf2bb8b1b43b4970e0efeca19643e5e5d78325 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.325969) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.326459) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.326758) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006491 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.352371) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.352653) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.368918) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.369174) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004662 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.418128) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.418446) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.418849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.419126) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 05:28:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 39.8M=0s 2015-11-26 05:28:32 (39.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.186528 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.617611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.617925) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019928 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.667429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:32.667779) ========= bash -c 'python archiver_client.py mozharness --repo projects/ash --rev ffcf2bb8b1b43b4970e0efeca19643e5e5d78325 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/ash --rev ffcf2bb8b1b43b4970e0efeca19643e5e5d78325 --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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 05:28:32,964 truncating revision to first 12 chars 2015-11-26 05:28:32,964 Setting DEBUG logging. 2015-11-26 05:28:32,964 attempt 1/10 2015-11-26 05:28:32,964 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/ffcf2bb8b1b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 05:28:33,266 unpacking tar archive at: ash-ffcf2bb8b1b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.568530 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:33.248958) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:33.249261) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:33.258611) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:33.258872) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 05:28:33.259238) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-11-26 05:28:33.259574) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 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', 'Ash', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Ash'] 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:28:33 INFO - MultiFileLogger online at 20151126 05:28:33 in /builds/slave/test-pgo 05:28:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash 05:28:33 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:28:33 INFO - {'append_to_log': False, 05:28:33 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:28:33 INFO - 'blob_upload_branch': 'Ash', 05:28:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:28:33 INFO - 'branch': 'Ash', 05:28:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:33 INFO - 'config_files': ('talos/linux_config.py',), 05:28:33 INFO - 'default_actions': ('clobber', 05:28:33 INFO - 'read-buildbot-config', 05:28:33 INFO - 'download-and-extract', 05:28:33 INFO - 'populate-webroot', 05:28:33 INFO - 'create-virtualenv', 05:28:33 INFO - 'install', 05:28:33 INFO - 'run-tests'), 05:28:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:33 INFO - 'download_minidump_stackwalk': True, 05:28:33 INFO - 'download_symbols': 'ondemand', 05:28:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:33 INFO - 'installer_path': 'installer.exe', 05:28:33 INFO - 'log_level': 'info', 05:28:33 INFO - 'log_name': 'talos', 05:28:33 INFO - 'log_to_console': True, 05:28:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:28:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:28:33 INFO - 'opt_config_files': (), 05:28:33 INFO - 'pip_index': False, 05:28:33 INFO - 'sps_profile': False, 05:28:33 INFO - 'sps_profile_interval': 0, 05:28:33 INFO - 'suite': 'dromaeojs', 05:28:33 INFO - 'system_bits': '64', 05:28:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:33 INFO - 'title': 'talos-linux64-ix-022', 05:28:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:33 INFO - 'use_talos_json': True, 05:28:33 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:28:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:33 INFO - 'work_dir': 'build'} 05:28:33 INFO - ##### 05:28:33 INFO - ##### Running clobber step. 05:28:33 INFO - ##### 05:28:33 INFO - Running pre-action listener: _resource_record_pre_action 05:28:33 INFO - Running main action method: clobber 05:28:33 INFO - rmtree: /builds/slave/test-pgo/build 05:28:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:28:34 INFO - Running post-action listener: _resource_record_post_action 05:28:34 INFO - ##### 05:28:34 INFO - ##### Running read-buildbot-config step. 05:28:34 INFO - ##### 05:28:34 INFO - Running pre-action listener: _resource_record_pre_action 05:28:34 INFO - Running main action method: read_buildbot_config 05:28:34 INFO - Using buildbot properties: 05:28:34 INFO - { 05:28:34 INFO - "properties": { 05:28:34 INFO - "buildnumber": 0, 05:28:34 INFO - "product": "firefox", 05:28:34 INFO - "script_repo_revision": "production", 05:28:34 INFO - "builddir": "ash_ubuntu64_hw_test-dromaeojs", 05:28:34 INFO - "repository": "", 05:28:34 INFO - "buildername": "Ubuntu HW 12.04 x64 ash pgo talos dromaeojs", 05:28:34 INFO - "buildid": "20151126040238", 05:28:34 INFO - "slavename": "talos-linux64-ix-022", 05:28:34 INFO - "stage_platform": "linux64-pgo", 05:28:34 INFO - "basedir": "/builds/slave/test-pgo", 05:28:34 INFO - "nightly_build": "True", 05:28:34 INFO - "project": "", 05:28:34 INFO - "platform": "ubuntu64_hw", 05:28:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:28:34 INFO - "slavebuilddir": "test", 05:28:34 INFO - "scheduler": "tests-ash-linux64-pgo-talos", 05:28:34 INFO - "branch": "ash", 05:28:34 INFO - "repo_path": "projects/ash", 05:28:34 INFO - "pgo_build": "True", 05:28:34 INFO - "builduid": "934e165d416a48f79b856c40ca54b1e4", 05:28:34 INFO - "revision": "ffcf2bb8b1b43b4970e0efeca19643e5e5d78325" 05:28:34 INFO - }, 05:28:34 INFO - "sourcestamp": { 05:28:34 INFO - "repository": "", 05:28:34 INFO - "hasPatch": false, 05:28:34 INFO - "project": "", 05:28:34 INFO - "branch": "ash-linux64-pgo-talos", 05:28:34 INFO - "changes": [ 05:28:34 INFO - { 05:28:34 INFO - "category": null, 05:28:34 INFO - "files": [ 05:28:34 INFO - { 05:28:34 INFO - "url": null, 05:28:34 INFO - "name": "https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:28:34 INFO - } 05:28:34 INFO - ], 05:28:34 INFO - "repository": "", 05:28:34 INFO - "rev": "ffcf2bb8b1b43b4970e0efeca19643e5e5d78325", 05:28:34 INFO - "who": "sendchange", 05:28:34 INFO - "when": 1448544471, 05:28:34 INFO - "number": 6710772, 05:28:34 INFO - "comments": "", 05:28:34 INFO - "project": "", 05:28:34 INFO - "at": "Thu 26 Nov 2015 05:27:51", 05:28:34 INFO - "branch": "ash-linux64-pgo-talos", 05:28:34 INFO - "revlink": "", 05:28:34 INFO - "properties": [ 05:28:34 INFO - [ 05:28:34 INFO - "buildid", 05:28:34 INFO - "20151126040238", 05:28:34 INFO - "Change" 05:28:34 INFO - ], 05:28:34 INFO - [ 05:28:34 INFO - "builduid", 05:28:34 INFO - "934e165d416a48f79b856c40ca54b1e4", 05:28:34 INFO - "Change" 05:28:34 INFO - ], 05:28:34 INFO - [ 05:28:34 INFO - "nightly_build", 05:28:34 INFO - "True", 05:28:34 INFO - "Change" 05:28:34 INFO - ], 05:28:34 INFO - [ 05:28:34 INFO - "pgo_build", 05:28:34 INFO - "True", 05:28:34 INFO - "Change" 05:28:34 INFO - ] 05:28:34 INFO - ], 05:28:34 INFO - "revision": "ffcf2bb8b1b43b4970e0efeca19643e5e5d78325" 05:28:34 INFO - } 05:28:34 INFO - ], 05:28:34 INFO - "revision": "ffcf2bb8b1b43b4970e0efeca19643e5e5d78325" 05:28:34 INFO - } 05:28:34 INFO - } 05:28:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:28:34 INFO - Running post-action listener: _resource_record_post_action 05:28:34 INFO - ##### 05:28:34 INFO - ##### Running download-and-extract step. 05:28:34 INFO - ##### 05:28:34 INFO - Running pre-action listener: _resource_record_pre_action 05:28:34 INFO - Running main action method: download_and_extract 05:28:34 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:28:34 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')]} 05:28:34 INFO - https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:28:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/test_packages.json 05:28:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/test_packages.json 05:28:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:28:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:28:36 INFO - Downloaded 1302 bytes. 05:28:36 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:28:36 INFO - Using the following test package requirements: 05:28:36 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:36 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:28:36 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'jsshell-linux-x86_64.zip'], 05:28:36 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:28:36 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:36 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:28:36 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:28:36 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:28:36 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:36 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:36 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:28:36 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:28:36 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')]} 05:28:36 INFO - https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:37 INFO - Downloaded 22529863 bytes. 05:28:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:38 INFO - Return code: 0 05:28:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:28: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')]} 05:28:38 INFO - https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:40 INFO - Downloaded 22529863 bytes. 05:28:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:42 INFO - Return code: 0 05:28: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')]} 05:28:42 INFO - https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:28:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:28:43 INFO - Downloaded 11131717 bytes. 05:28:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:28:43 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:28:43 INFO - Return code: 0 05:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:43 INFO - https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:28:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:28:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:28:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:28:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:28:44 INFO - Downloaded 53729615 bytes. 05:28:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:28:44 INFO - mkdir: /builds/slave/test-pgo/properties 05:28:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:28:44 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:28:44 INFO - Contents: 05:28:44 INFO - build_url:https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:28:44 INFO - Running post-action listener: _resource_record_post_action 05:28:44 INFO - Running post-action listener: set_extra_try_arguments 05:28:44 INFO - ##### 05:28:44 INFO - ##### Running populate-webroot step. 05:28:44 INFO - ##### 05:28:44 INFO - Running pre-action listener: _resource_record_pre_action 05:28:44 INFO - Running main action method: populate_webroot 05:28:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:28:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:28:44 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:28:44 INFO - '../fennec_ids.txt'], 05:28:44 INFO - 'tests': ['tcheck2']}, 05:28:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:28:44 INFO - '--tppagecycles', 05:28:44 INFO - '7'], 05:28:44 INFO - 'tests': ['tsvgm']}}, 05:28:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:28:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:28:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:28:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:28:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'tests': ['damp', 'tps']}, 05:28:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['damp', 'tps']}, 05:28:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:28:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:28:44 INFO - 'other': {'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'other_l64': {'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'other_nol64': {'tests': ['a11yr', 05:28:44 INFO - 'ts_paint', 05:28:44 INFO - 'tpaint', 05:28:44 INFO - 'sessionrestore', 05:28:44 INFO - 'sessionrestore_no_auto_restore']}, 05:28:44 INFO - 'svgr': {'tests': ['tsvgx', 05:28:44 INFO - 'tsvgr_opacity', 05:28:44 INFO - 'tart', 05:28:44 INFO - 'tscrollx', 05:28:44 INFO - 'cart']}, 05:28:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['tsvgx', 05:28:44 INFO - 'tsvgr_opacity', 05:28:44 INFO - 'tart', 05:28:44 INFO - 'tscrollx', 05:28:44 INFO - 'cart']}, 05:28:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'tests': ['tp5o']}, 05:28:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'talos_options': ['--e10s'], 05:28:44 INFO - 'tests': ['tp5o']}, 05:28:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'talos_options': ['--xperf_path', 05:28:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:44 INFO - 'tests': ['tp5n']}, 05:28:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:28:44 INFO - 'talos_options': ['--e10s', 05:28:44 INFO - '--xperf_path', 05:28:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:28:44 INFO - 'tests': ['tp5n']}}, 05:28:44 INFO - 'talos.zip': {'path': '', 05:28:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:28:44 INFO - Running post-action listener: _resource_record_post_action 05:28:44 INFO - ##### 05:28:44 INFO - ##### Running create-virtualenv step. 05:28:44 INFO - ##### 05:28:44 INFO - Running pre-action listener: _resource_record_pre_action 05:28:44 INFO - Running main action method: create_virtualenv 05:28:44 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:28:44 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 05:28:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:28:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:28:44 INFO - Using real prefix '/usr' 05:28:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:28:45 INFO - Installing distribute.............................................................................................................................................................................................done. 05:28:47 INFO - Installing pip.................done. 05:28:47 INFO - Return code: 0 05:28:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:28:47 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')]} 05:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:47 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')]} 05:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:47 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:47 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 05:28:47 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 05:28:47 INFO - Using env: {'DISPLAY': ':0', 05:28:47 INFO - 'HOME': '/home/cltbld', 05:28:47 INFO - 'LANG': 'en_US.UTF-8', 05:28:47 INFO - 'LANGUAGE': 'en_US:en', 05:28:47 INFO - 'LOGNAME': 'cltbld', 05:28:47 INFO - 'MAIL': '/var/mail/cltbld', 05:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:47 INFO - 'MOZ_NO_REMOTE': '1', 05:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:47 INFO - 'NO_EM_RESTART': '1', 05:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:47 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:47 INFO - 'SHELL': '/bin/bash', 05:28:47 INFO - 'SHLVL': '1', 05:28:47 INFO - 'TERM': 'linux', 05:28:47 INFO - 'TMOUT': '86400', 05:28:47 INFO - 'USER': 'cltbld', 05:28:47 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:47 INFO - '_': '/tools/buildbot/bin/python'} 05:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:47 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 05:28:47 INFO - Cleaning up... 05:28:47 INFO - Return code: 0 05:28:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:28:47 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')]} 05:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:47 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')]} 05:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:47 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:47 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 05:28:47 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 05:28:47 INFO - Using env: {'DISPLAY': ':0', 05:28:47 INFO - 'HOME': '/home/cltbld', 05:28:47 INFO - 'LANG': 'en_US.UTF-8', 05:28:47 INFO - 'LANGUAGE': 'en_US:en', 05:28:47 INFO - 'LOGNAME': 'cltbld', 05:28:47 INFO - 'MAIL': '/var/mail/cltbld', 05:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:47 INFO - 'MOZ_NO_REMOTE': '1', 05:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:47 INFO - 'NO_EM_RESTART': '1', 05:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:47 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:47 INFO - 'SHELL': '/bin/bash', 05:28:47 INFO - 'SHLVL': '1', 05:28:47 INFO - 'TERM': 'linux', 05:28:47 INFO - 'TMOUT': '86400', 05:28:47 INFO - 'USER': 'cltbld', 05:28:47 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:47 INFO - '_': '/tools/buildbot/bin/python'} 05:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:47 INFO - Downloading/unpacking psutil>=0.7.1 05:28:47 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 05:28:47 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 05:28:47 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 05:28:47 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 05:28:47 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 05:28:47 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 05:28:49 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:28:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:28:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:28:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:49 INFO - Installing collected packages: psutil 05:28:49 INFO - Running setup.py install for psutil 05:28:49 INFO - building 'psutil._psutil_linux' extension 05:28:49 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 05:28:49 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 05:28:49 INFO - building 'psutil._psutil_posix' extension 05:28:49 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 05:28:49 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 05:28:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:28:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:28:50 INFO - Successfully installed psutil 05:28:50 INFO - Cleaning up... 05:28:50 INFO - Return code: 0 05:28:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:50 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:50 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 05:28:50 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 05:28:50 INFO - Using env: {'DISPLAY': ':0', 05:28:50 INFO - 'HOME': '/home/cltbld', 05:28:50 INFO - 'LANG': 'en_US.UTF-8', 05:28:50 INFO - 'LANGUAGE': 'en_US:en', 05:28:50 INFO - 'LOGNAME': 'cltbld', 05:28:50 INFO - 'MAIL': '/var/mail/cltbld', 05:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:50 INFO - 'MOZ_NO_REMOTE': '1', 05:28:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:50 INFO - 'NO_EM_RESTART': '1', 05:28:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:50 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:50 INFO - 'SHELL': '/bin/bash', 05:28:50 INFO - 'SHLVL': '1', 05:28:50 INFO - 'TERM': 'linux', 05:28:50 INFO - 'TMOUT': '86400', 05:28:50 INFO - 'USER': 'cltbld', 05:28:50 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:50 INFO - '_': '/tools/buildbot/bin/python'} 05:28:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:28:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:28:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:28:52 INFO - Installing collected packages: mozsystemmonitor 05:28:52 INFO - Running setup.py install for mozsystemmonitor 05:28:52 INFO - Successfully installed mozsystemmonitor 05:28:52 INFO - Cleaning up... 05:28:52 INFO - Return code: 0 05:28:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:28:52 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')]} 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 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')]} 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:52 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:52 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 05:28:52 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 05:28:52 INFO - Using env: {'DISPLAY': ':0', 05:28:52 INFO - 'HOME': '/home/cltbld', 05:28:52 INFO - 'LANG': 'en_US.UTF-8', 05:28:52 INFO - 'LANGUAGE': 'en_US:en', 05:28:52 INFO - 'LOGNAME': 'cltbld', 05:28:52 INFO - 'MAIL': '/var/mail/cltbld', 05:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:52 INFO - 'MOZ_NO_REMOTE': '1', 05:28:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:52 INFO - 'NO_EM_RESTART': '1', 05:28:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:52 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:52 INFO - 'SHELL': '/bin/bash', 05:28:52 INFO - 'SHLVL': '1', 05:28:52 INFO - 'TERM': 'linux', 05:28:52 INFO - 'TMOUT': '86400', 05:28:52 INFO - 'USER': 'cltbld', 05:28:52 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:52 INFO - '_': '/tools/buildbot/bin/python'} 05:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:28:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:28:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:28:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:28:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:28:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 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 05:28:54 INFO - Downloading docopt-0.6.1.tar.gz 05:28:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:28:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:54 INFO - Running setup.py install for blobuploader 05:28:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:54 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:28:54 INFO - Running setup.py install for requests 05:28:55 INFO - Running setup.py install for docopt 05:28:55 INFO - Successfully installed blobuploader requests docopt 05:28:55 INFO - Cleaning up... 05:28:55 INFO - Return code: 0 05:28:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:28:55 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')]} 05:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:55 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')]} 05:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:55 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:55 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 05:28:55 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 05:28:55 INFO - Using env: {'DISPLAY': ':0', 05:28:55 INFO - 'HOME': '/home/cltbld', 05:28:55 INFO - 'LANG': 'en_US.UTF-8', 05:28:55 INFO - 'LANGUAGE': 'en_US:en', 05:28:55 INFO - 'LOGNAME': 'cltbld', 05:28:55 INFO - 'MAIL': '/var/mail/cltbld', 05:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:55 INFO - 'MOZ_NO_REMOTE': '1', 05:28:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:55 INFO - 'NO_EM_RESTART': '1', 05:28:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:55 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:55 INFO - 'SHELL': '/bin/bash', 05:28:55 INFO - 'SHLVL': '1', 05:28:55 INFO - 'TERM': 'linux', 05:28:55 INFO - 'TMOUT': '86400', 05:28:55 INFO - 'USER': 'cltbld', 05:28:55 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:55 INFO - '_': '/tools/buildbot/bin/python'} 05:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:28:55 INFO - Running setup.py (path:/tmp/pip-p0mkdU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:28:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:28:55 INFO - Running setup.py (path:/tmp/pip-7cJ6g2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:28:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:28:55 INFO - Running setup.py (path:/tmp/pip-1R7o5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:28:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:28:55 INFO - Running setup.py (path:/tmp/pip-8ZKkxC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:28:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:28:55 INFO - Running setup.py (path:/tmp/pip-hzqjkU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:28:56 INFO - Running setup.py (path:/tmp/pip-yXXDZ3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:28:56 INFO - Running setup.py (path:/tmp/pip-3yfBra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:28:56 INFO - Running setup.py (path:/tmp/pip-2TgAm9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:28:56 INFO - Running setup.py (path:/tmp/pip-jS9Cxd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:28:56 INFO - Running setup.py (path:/tmp/pip-IwYaOA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:28:56 INFO - Running setup.py (path:/tmp/pip-6Kl_8F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:28:56 INFO - Running setup.py (path:/tmp/pip-0ZE4it-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:28:56 INFO - Running setup.py (path:/tmp/pip-_n4Oci-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:28:56 INFO - Running setup.py (path:/tmp/pip-_7u_UK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:28:56 INFO - Running setup.py (path:/tmp/pip-fDtNzd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:28:56 INFO - Running setup.py (path:/tmp/pip-N8yoTm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:28:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:28:56 INFO - Running setup.py (path:/tmp/pip-4HlERS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:28:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:56 INFO - Running setup.py install for manifestparser 05:28:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for mozcrash 05:28:57 INFO - Running setup.py install for mozdebug 05:28:57 INFO - Running setup.py install for mozdevice 05:28:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for mozfile 05:28:57 INFO - Running setup.py install for mozhttpd 05:28:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for mozinfo 05:28:57 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for mozInstall 05:28:57 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for mozleak 05:28:57 INFO - Running setup.py install for mozlog 05:28:57 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:28:57 INFO - Running setup.py install for moznetwork 05:28:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Running setup.py install for mozprocess 05:28:58 INFO - Running setup.py install for mozprofile 05:28:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Running setup.py install for mozrunner 05:28:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Running setup.py install for mozscreenshot 05:28:58 INFO - Running setup.py install for moztest 05:28:58 INFO - Running setup.py install for mozversion 05:28:58 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:28:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:58 INFO - Cleaning up... 05:28:58 INFO - Return code: 0 05:28:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:28:58 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')]} 05:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:58 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')]} 05:28:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:58 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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:28:58 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 05:28:58 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 05:28:58 INFO - Using env: {'DISPLAY': ':0', 05:28:58 INFO - 'HOME': '/home/cltbld', 05:28:58 INFO - 'LANG': 'en_US.UTF-8', 05:28:58 INFO - 'LANGUAGE': 'en_US:en', 05:28:58 INFO - 'LOGNAME': 'cltbld', 05:28:58 INFO - 'MAIL': '/var/mail/cltbld', 05:28:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:58 INFO - 'MOZ_NO_REMOTE': '1', 05:28:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:28:58 INFO - 'NO_EM_RESTART': '1', 05:28:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:28:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:28:58 INFO - 'PWD': '/builds/slave/test-pgo', 05:28:58 INFO - 'SHELL': '/bin/bash', 05:28:58 INFO - 'SHLVL': '1', 05:28:58 INFO - 'TERM': 'linux', 05:28:58 INFO - 'TMOUT': '86400', 05:28:58 INFO - 'USER': 'cltbld', 05:28:58 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:28:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:58 INFO - '_': '/tools/buildbot/bin/python'} 05:28:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:28:58 INFO - Running setup.py (path:/tmp/pip-XLsgKy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:28:58 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)) 05:28:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:28:58 INFO - Running setup.py (path:/tmp/pip-Qwftdd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:28:59 INFO - Running setup.py (path:/tmp/pip-X2YVdI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:28:59 INFO - Running setup.py (path:/tmp/pip-r9zRmQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:28:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:28:59 INFO - Running setup.py (path:/tmp/pip-VJQqPm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:28:59 INFO - Running setup.py (path:/tmp/pip-_9DSli-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:28:59 INFO - Running setup.py (path:/tmp/pip-LR7nFh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:28:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:28:59 INFO - Running setup.py (path:/tmp/pip-yi0VdE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:28:59 INFO - Running setup.py (path:/tmp/pip-x8DxwG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:28:59 INFO - Running setup.py (path:/tmp/pip-GRfr55-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:28:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:28:59 INFO - Running setup.py (path:/tmp/pip-_MvMKv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:28:59 INFO - Running setup.py (path:/tmp/pip-aW0YWM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:28:59 INFO - Running setup.py (path:/tmp/pip-pNC5iY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:28:59 INFO - Running setup.py (path:/tmp/pip-by82N2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:28:59 INFO - Running setup.py (path:/tmp/pip-9XLmoZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:28:59 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)) 05:28:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:28:59 INFO - Running setup.py (path:/tmp/pip-H_UoxV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:00 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)) 05:29:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:00 INFO - Running setup.py (path:/tmp/pip-h0cKTR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:00 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)) 05:29:00 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)) 05:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:29:00 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)) 05:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:29:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:29:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:00 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 05:29:01 INFO - Downloading blessings-1.5.1.tar.gz 05:29:01 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 05:29:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:01 INFO - Installing collected packages: blessings 05:29:01 INFO - Running setup.py install for blessings 05:29:01 INFO - Successfully installed blessings 05:29:01 INFO - Cleaning up... 05:29:02 INFO - Return code: 0 05:29:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:29:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:02 INFO - Reading from file tmpfile_stdout 05:29:02 INFO - Current package versions: 05:29:02 INFO - argparse == 1.2.1 05:29:02 INFO - blessings == 1.5.1 05:29:02 INFO - blobuploader == 1.2.4 05:29:02 INFO - docopt == 0.6.1 05:29:02 INFO - manifestparser == 1.1 05:29:02 INFO - mozInstall == 1.12 05:29:02 INFO - mozcrash == 0.16 05:29:02 INFO - mozdebug == 0.1 05:29:02 INFO - mozdevice == 0.47 05:29:02 INFO - mozfile == 1.2 05:29:02 INFO - mozhttpd == 0.7 05:29:02 INFO - mozinfo == 0.9 05:29:02 INFO - mozleak == 0.1 05:29:02 INFO - mozlog == 3.1 05:29:02 INFO - moznetwork == 0.27 05:29:02 INFO - mozprocess == 0.22 05:29:02 INFO - mozprofile == 0.27 05:29:02 INFO - mozrunner == 6.11 05:29:02 INFO - mozscreenshot == 0.1 05:29:02 INFO - mozsystemmonitor == 0.0 05:29:02 INFO - moztest == 0.7 05:29:02 INFO - mozversion == 1.4 05:29:02 INFO - psutil == 3.1.1 05:29:02 INFO - requests == 1.2.3 05:29:02 INFO - wsgiref == 0.1.2 05:29:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29: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')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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', '-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 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:29:02 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 05:29:02 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 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 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)) 05:29:02 INFO - Cleaning up... 05:29:02 INFO - Return code: 0 05:29:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:29: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')]} 05:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11cf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x129bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, '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': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', '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 05:29: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', 'jsonschema'] in /builds/slave/test-pgo/build 05:29: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 jsonschema 05:29:02 INFO - Using env: {'DISPLAY': ':0', 05:29:02 INFO - 'HOME': '/home/cltbld', 05:29:02 INFO - 'LANG': 'en_US.UTF-8', 05:29:02 INFO - 'LANGUAGE': 'en_US:en', 05:29:02 INFO - 'LOGNAME': 'cltbld', 05:29:02 INFO - 'MAIL': '/var/mail/cltbld', 05:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:02 INFO - 'MOZ_NO_REMOTE': '1', 05:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:02 INFO - 'NO_EM_RESTART': '1', 05:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:02 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:02 INFO - 'SHELL': '/bin/bash', 05:29:02 INFO - 'SHLVL': '1', 05:29:02 INFO - 'TERM': 'linux', 05:29:02 INFO - 'TMOUT': '86400', 05:29:02 INFO - 'USER': 'cltbld', 05:29:02 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:02 INFO - '_': '/tools/buildbot/bin/python'} 05:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:02 INFO - Downloading/unpacking jsonschema 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:04 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 05:29:04 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29: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 05:29:04 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:29:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:04 INFO - warning: no files found matching '*.txt' 05:29:04 INFO - no previously-included directories found matching 'build' 05:29:04 INFO - no previously-included directories found matching 'dist' 05:29:04 INFO - no previously-included directories found matching '.git*' 05:29:04 INFO - Installing collected packages: jsonschema, functools32 05:29:04 INFO - Running setup.py install for functools32 05:29:04 INFO - warning: no files found matching '*.txt' 05:29:04 INFO - no previously-included directories found matching 'build' 05:29:04 INFO - no previously-included directories found matching 'dist' 05:29:04 INFO - no previously-included directories found matching '.git*' 05:29:04 INFO - Successfully installed jsonschema functools32 05:29:04 INFO - Cleaning up... 05:29:04 INFO - Return code: 0 05:29:04 INFO - Running post-action listener: _resource_record_post_action 05:29:04 INFO - Running post-action listener: _start_resource_monitoring 05:29:04 INFO - Starting resource monitoring. 05:29:04 INFO - ##### 05:29:04 INFO - ##### Running install step. 05:29:04 INFO - ##### 05:29:04 INFO - Running pre-action listener: _resource_record_pre_action 05:29:04 INFO - Running main action method: install 05:29:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:05 INFO - Reading from file tmpfile_stdout 05:29:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:29:05 INFO - Reading from file tmpfile_stdout 05:29:05 INFO - Output received: 05:29:05 INFO - Usage: mozinstall [options] installer 05:29:05 INFO - Options: 05:29:05 INFO - -h, --help show this help message and exit 05:29:05 INFO - -d DEST, --destination=DEST 05:29:05 INFO - Directory to install application into. [default: 05:29:05 INFO - "/builds/slave/test-pgo"] 05:29:05 INFO - --app=APP Application being installed. [default: firefox] 05:29:05 INFO - mkdir: /builds/slave/test-pgo/build/application 05:29:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:29:20 INFO - Reading from file tmpfile_stdout 05:29:20 INFO - Output received: 05:29:20 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:29:20 INFO - Running post-action listener: _resource_record_post_action 05:29:20 INFO - ##### 05:29:20 INFO - ##### Running run-tests step. 05:29:20 INFO - ##### 05:29:20 INFO - Running pre-action listener: _resource_record_pre_action 05:29:20 INFO - Running main action method: run_tests 05:29:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:29:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:29:20 INFO - Python 2.7.3 05:29:20 INFO - Return code: 0 05:29:20 INFO - grabbing minidump binary from tooltool 05:29:20 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')]} 05:29:20 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 0x132ae50>, '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 0x132bd50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132c1e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:29:20 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 05:29:20 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 05:29:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:20 INFO - Return code: 0 05:29:20 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:29:20 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:29:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:29:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:29:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Ash --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost 05:29:20 INFO - Using env: {'DISPLAY': ':0', 05:29:20 INFO - 'HOME': '/home/cltbld', 05:29:20 INFO - 'LANG': 'en_US.UTF-8', 05:29:20 INFO - 'LANGUAGE': 'en_US:en', 05:29:20 INFO - 'LOGNAME': 'cltbld', 05:29:20 INFO - 'MAIL': '/var/mail/cltbld', 05:29:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:20 INFO - 'MOZ_NO_REMOTE': '1', 05:29:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:20 INFO - 'NO_EM_RESTART': '1', 05:29:20 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:20 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:20 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:29:20 INFO - 'SHELL': '/bin/bash', 05:29:20 INFO - 'SHLVL': '1', 05:29:20 INFO - 'TERM': 'linux', 05:29:20 INFO - 'TMOUT': '86400', 05:29:20 INFO - 'USER': 'cltbld', 05:29:20 INFO - 'XDG_SESSION_COOKIE': 'b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703', 05:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:20 INFO - '_': '/tools/buildbot/bin/python'} 05:29:20 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Ash', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost'] with output_timeout 3600 05:29:20 INFO - mozversion INFO | application_buildid: 20151126040238 05:29:20 INFO - mozversion INFO | application_changeset: ffcf2bb8b1b43b4970e0efeca19643e5e5d78325 05:29:20 INFO - mozversion INFO | application_display_name: Nightly 05:29:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:20 INFO - mozversion INFO | application_name: Firefox 05:29:20 INFO - mozversion INFO | application_remotingname: firefox 05:29:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/projects/ash 05:29:20 INFO - mozversion INFO | application_vendor: Mozilla 05:29:20 INFO - mozversion INFO | application_version: 45.0a1 05:29:20 INFO - mozversion INFO | platform_buildid: 20151126040238 05:29:20 INFO - mozversion INFO | platform_changeset: ffcf2bb8b1b43b4970e0efeca19643e5e5d78325 05:29:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/projects/ash 05:29:20 INFO - mozversion INFO | platform_version: 45.0a1 05:29:20 INFO - 2015-11-26 05:29:20,941 DEBUG : using testdate: 1448544560 05:29:20 INFO - 2015-11-26 05:29:20,941 DEBUG : actual date: 1448544560 05:29:20 INFO - 2015-11-26 05:29:20,989 INFO : starting webserver on 'localhost:41162' 05:29:20 INFO - 2015-11-26 05:29:20,990 INFO : Starting test suite talos-linux64-ix-022 05:29:20 INFO - 2015-11-26 05:29:20,990 INFO : Starting test dromaeo_css 05:29:20 INFO - 2015-11-26 05:29:20,990 DEBUG : operating with platform_type : linux_ 05:29:20 INFO - 2015-11-26 05:29:20,990 INFO : Initialising browser for dromaeo_css test... 05:29:21 INFO - 2015-11-26 05:29:21,006 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoQxOIA/profile http://localhost:41162/getInfo.html 05:29:24 INFO - 2015-11-26 05:29:24,981 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:24 INFO - 2015-11-26 05:29:24,981 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:24 INFO - 2015-11-26 05:29:24,981 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:29:24 INFO - 2015-11-26 05:29:24,981 DEBUG : BROWSER_OUTPUT: __metrics 05:29:25 INFO - 2015-11-26 05:29:25,102 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] 05:29:26 INFO - 2015-11-26 05:29:26,525 INFO : Browser initialized. 05:29:26 INFO - 2015-11-26 05:29:26,526 INFO : Running cycle 1/1 for dromaeo_css test... 05:29:26 INFO - 2015-11-26 05:29:26,526 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoQxOIA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:38 INFO - 2015-11-26 05:29:38,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 145932288 05:29:38 INFO - 2015-11-26 05:29:38,165 DEBUG : BROWSER_OUTPUT: 05:30:28 INFO - 2015-11-26 05:30:28,881 DEBUG : BROWSER_OUTPUT: 1448544628876 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] 05:32:38 INFO - 2015-11-26 05:32:38,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41162/tests/dromaeo/cssquery-ext.html) 05:32:40 INFO - 2015-11-26 05:32:40,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 268075008 05:32:40 INFO - 2015-11-26 05:32:40,056 DEBUG : BROWSER_OUTPUT: 05:35:42 INFO - 2015-11-26 05:35:42,422 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-ext.html (next: http://localhost:41162/tests/dromaeo/cssquery-jquery.html) 05:35:43 INFO - 2015-11-26 05:35:43,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 233824256 05:35:43 INFO - 2015-11-26 05:35:43,003 DEBUG : BROWSER_OUTPUT: 05:38:44 INFO - 2015-11-26 05:38:44,893 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41162/tests/dromaeo/cssquery-mootools.html) 05:38:45 INFO - 2015-11-26 05:38:45,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 228052992 05:38:45 INFO - 2015-11-26 05:38:45,462 DEBUG : BROWSER_OUTPUT: 05:41:47 INFO - 2015-11-26 05:41:47,306 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41162/tests/dromaeo/cssquery-prototype.html) 05:41:47 INFO - 2015-11-26 05:41:47,866 DEBUG : BROWSER_OUTPUT: RSS: Main: 206381056 05:41:47 INFO - 2015-11-26 05:41:47,867 DEBUG : BROWSER_OUTPUT: 05:44:50 INFO - 2015-11-26 05:44:50,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41162/tests/dromaeo/cssquery-yui.html) 05:44:50 INFO - 2015-11-26 05:44:50,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 210403328 05:44:50 INFO - 2015-11-26 05:44:50,965 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/dromaeo/cssquery-yui.html (next: http://localhost:41162/tests/dromaeo/cssquery-dojo.html) 05:47:53 INFO - 2015-11-26 05:47:53,737 DEBUG : BROWSER_OUTPUT: RSS: Main: 268292096 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3190.218396492859;3240.7569432292585;3287.0631869917065;3290.2432440538673;3310.817905015225;5230.895382092356;5379.8386048418515;5395.649282796616;5420.051490989076;5432.293801805769;5337.626366154371;5345.358556973159;5360.410354860962;5415.512603865652;5507.146392309189;4938.678985865925;4942.728149951751;4965.3048573199785;4974.403071631409;5034.521720436553;4892.804287828482;5031.647784655075;5041.647084704072;5072.873178170538;5109.284700141983;4858.975705121469;4964.304997300381;4987.002599480118;5004.024215278022;5031.6729412588065;4974.328465657126;4976.576990955773;4991.450940396553;4998.175301075318;5010.874728131789;4868.975655121737;4935.851924442272;4957.752112394402;4964.032013757319;5014.222795466709;5162.767675454614;5207.010667973111;5239.5546378557865;5239.580833533309;5241.895162096736;5926.318473375531;5938.10928360745;5948.821535353946;5952.702364881782;5970.582059255854;5959.582829201958;5972.522198224132;5976.491998180117;5978.342382337939;5982.670953097578;5373.731313434313;5376.327959005124;5470.152126420411;5486.31421072362;5486.341639003305;6002.519798416116;6004.3395226525045;6008.909866352008;6011.158437818709;6027.276726792813;5690.573207009408;5807.332156802022;5808.128780682847;5811.506021988093;5820.563457740686;5723.3418156912485;5845.7369418376275;5900.203472531153;5906.645601263938;5908.822735317947;5748.971255143698;5838.211841401441;5905.616134951214;5926.348101708809;5957.672328022;5759.8560035999435;5765.971170144123;5789.507891829242;5806.477417032487;5822.621529600562;4364.10535840152;4460.286354183315;4488.618467430239;4492.011757413364;4496.437945256843;3940.310445671996;4002.039510517455;4005.2990726622725;4029.5970402960056;4029.7179197456485;5521.944780552223;5567.693776842312;5567.805126820542;5571.665700057928;5575.386707462176;5731.398203188605;5785.421457854265;5842.386549412336;5892.705364731746;5901.350851406428;5534.640248383842;5596.608237423341;5613.915791263134;5618.634788738719;5648.039833228361;5106.591472682177;5117.334746483008;5126.512981266774;5129.512696293846;5183.222516622537;5945.672987985616;6070.785842831539;6086.995645718495;6113.663748493786;6114;5746;5797.8550536236935;5803.47768700819;5806.941930580809;5855.2095467113095;6038.365971572922;6042.0332746760305;6043.7884674036195;6044.486218671462;6045.304789949212;5676.517496012885;5713.342965559014;5790.768369265183;5794.768209271582;5802.970985145047;5761;5782.595218334676;5800.187973683604;5806.1000544915605;5850.5904586678525;5733.168690539932;5735.627184233012;5736.6558006519745;5739.053056245672;5741.569382296261;5534.944650553443;5615.129654903497;5652.360815363499;5731;5740.0815869460685;5497.835064948058;5615.382307946041;5617.831465056055;5621.072523033654;5665.886682266414;5772.422757724194;5776.9133462998925;5783.826485205451;5788.855278618068;5792.94207057924;5494.697791621499;5595.860103497445;5609;5610.13042978339;5650.237217975685 05:47:53 INFO - 2015-11-26 05:47:53,738 DEBUG : BROWSER_OUTPUT: |1;ext.html;554.0083250980745;595.81529725785;601.9157317975485;611.8715069835337;614.5728718540611;31468.685313146863;39438.802805985964;39683.60316396835;39728;39787.40318895219;41881.790591047;42185;42303.365449518285;42337.57662423375;42403.57596424035;7457.179710231871;7531.736389226378;8776.60505277264;8953.641854325819;8955.19403253707;10231.130353919925;10413.739656508573;10442.425666588335;10449.059584637382;10457.895421045807;10187.54156062979;10234.89765102347;10277.537510812032;10290.588376464932;10294.17646588271;7703.383729301699;7865.842683146362;10163.949180254089;10283.948580257125;10574;10192.490375481271;10311.690649280534;10313.329633573869;10314.226433017553;10315.587376504931;39712;39932.600673993176;40286.194276114315;40331.7983410084;40344;3148.921276968071;3154.921126971821;3173.2701478660038;3269.0683155300862;3447.1554469155217;29424.852875735698;29840.16338188521;30177;30444.54333185004;30974.845125774453;3676.7610105343333;3733.6826369758605;3739.083924438503;3762.49206357141;3825.024618722236;280231;282814;283543;284097;284843;55251;55939;56047.719761400935;60705;61254.081188782206;49767.50232497701;70364.64817675878;71129;71408;71484.2851571488;15493.922530387501;15750.133742644264;15779.28993195309;15862.68274634524;15890;12290.385480725929;12296.938515307365;12298.815517767242;12307.938460307641;12316.81524777129;20078.89960550217;20322.695159572617;20372.69440958387;20431.693524597144;20444.182232710526;7904.170062143392;7966.203379661987;7977.760667179994;8020.679172833138;8118.718906715236;883.6774577279247;961.4567769210374;1003.1924300937815;1039.2361614213546;1066.8719753629614;28678.653950763186;29053;29393.412131757246;29395.55906661402;29430.705692942796;1008.6570566007592;1010.9435639756325;1040.9850395864123;1077.224398433127;1121.8149005414016;16194;17088.487345379657;17245;17289.48131556055;17364.652706946043;392952;398144;398921;399062;399358;54886;55410.445895541336;55430;55506.16740748892;55535.444645553835;1341.6578772413168;1348.2786709110776;1356.3286173344259;1361.761691703948;1361.7889227169806;28908;29812.403751925274;30200.395992080474;30410;32157.839210803795;42977.57022429861;43923.78038109789;48975.510244897094;49597;50441.24338135005;239512;241114;241167;241608;244308;37331.626683732815;38288.42567361548;38715.677059376525;38767.80616096901;38775.806120970345;41308.1738365237;41405.58594414017;44489.332660010776;44685.77657111824;44961;37139.442908356934;37551.43672844855;41375.172496550505;41862.79068604759;42675.57324426716;36411.63588364189;36791.81604091962;37203.813980929925;37409.43885841769;37827.24345513129;36598.45102323414;36946.63053369432;37623;37630.62369376271;37653.987680986;31828.68171318257;32567.511487327236;33726.66273337235;34206.14484637904;34350.14124646904;25565.488690226462;25768.355791105372;25790.484190316463;25908.611370829076;26062.34844128912 05:47:53 INFO - 2015-11-26 05:47:53,739 DEBUG : BROWSER_OUTPUT: |2;jquery.html;338.6122889291761;339.9490076488525;346.61179478983524;346.87859249262755;348.22867348822354;13634;13986.230757308344;16234.51296461105;16345.673086538261;17052.573685657848;13273.734525309488;15363.539093827176;15384.307706153222;15408.07551546908;15985.200739962984;4695.3191787190835;4747.667663263573;4792.520747925197;4794.88012799681;4804.279358096296;3649.051246675857;3775.7168212384045;4827.975860120694;4856.6357523185725;5232.947670523303;5251.76367063483;5264.763085661135;5310.15037593985;5329.680219186842;5349.277847490597;5285.814996475124;5287.127623942064;5378.650387724785;5805.564582656276;5807.709614519299;5172.612054095958;5173.870653233699;5185.585153187736;5189.636725429222;5190;19269.80730192708;19930.209770632122;20155.596888062155;20169.596608067903;20834.374968750963;2059.855810093294;2120.925767598142;2198.318521258415;2258.8757618330983;2403.687520622308;9400.952995235048;9522.714318570384;9679.854802177973;9683.128518433376;9697.51512424383;1747.1264367816093;1810.2035104554086;1817.7000794868964;1845.4832646858902;1852.3053854804448;61158.38841611571;61651;61689.38310616881;61708;63497.682511587605;9867.950660246654;10148.695539133838;10204.795904081877;10231.590736370461;10278;13231.007674424456;13243;13303.13529620591;13348.532801351907;13359.465621375231;7617.961910190525;7665.540067595962;7679.846403071907;7816.49192802466;7830.256125668053;7379.409647228209;7506.437017223621;7552.320291173711;7554.924450755422;7635.121960974448;4390.297552391602;4395;4398.802053907518;4404.933925991113;4413.404190434315;1707.1293640243566;1748.3793253395115;1762.5153082902048;1769.619531800669;1826.662067517487;802.4623502253504;935.6179741836912;938.6151677812064;1030.9688719050982;1041.656253436364;16054;16726;16825.495235142964;17033.444983252364;17181.226844791763;764.7514557768748;903.8807274750037;932.6455946740195;934.4766930518931;941.4774799985937;8491.660333586711;9326.440398591498;9604.231661467065;9607.663731769355;9622.085901839315;541789;542152;550830;574229;579306;77424.61287693639;78522.60738696385;79205;79283;79443;1086.7554800170058;1089.3409487260094;1105.6351404036648;1113.9387333696725;1113.9443027848574;14415.279236038366;14492.637684057561;14657.267136643339;14831.851681483047;14848.925755371154;33442.16394590155;33490.33019339648;33683.326333473684;33814;33869.15327116842;44591.33113003243;44680.32979505375;46225.53774462347;46459.07081858276;48953.265701015225;8815.471071735716;9315;9644.228461722905;9695.51522423862;9790.902090979;11036.282641628268;11218.495167717636;11415.315081095161;11474.082073433938;14025.579232623038;13724.862751372359;13828.516001939885;14149.221792801494;14457.493987710379;14799.704005919606;14047;14143.363548640542;14150.858491414954;14224.217668028357;14678.192699401636;14172.503962361268;14439.855601444271;14445;14767.187804670846;15094.773578396112;7958.403119765925;8173.673053078048;8646.697365592174;8940.552972351226;9033.322500812334;9787.80424391494;9916.206703463562;11874.643760687193;13542.458301668217;13744.862551374757 05:47:53 INFO - 2015-11-26 05:47:53,740 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1330.773768459362;1474.5502621700384;1522.322566457926;1668.1825905306403;1778.8754787164896;7018.894716579249;7537.736179233728;7854.450188486809;7860.135385107634;7864.252895974881;7606.619669016541;7753.069631644196;7814.062312522491;7832.451728379016;7845.293923546879;1969.1827891425041;2014.717939488473;2228.999999999996;2235.575240704269;2266.422062374092;2238.227811405071;2241.529278851443;2242.4618091657967;2243.607368710477;2245.8764767937755;2185.508260641359;2240.574290884727;2241.2827895073583;2241.8094461970672;2245.0009745663187;1939.6702560564738;2005.9799402006013;2186.256672731279;2225.321277010511;2227.7772222777753;2221.711177546925;2232.341459269503;2251.448395143184;2254.3124347074136;2258.503129311549;7536.321731044232;7546.32083112517;7582.545047297124;7589.81025474362;7599.620018999028;4524.751138687371;4589.036302376505;4593.563611456906;4598.448186217642;4625.514320996281;2909.61793148255;2925.3271747497984;2971.881124755029;2972.4203780262856;3018.5321275202377;883.4875772052194;887.4808237181256;900.2123142250531;907.5915837872983;920.9815803683956;10416.687499375032;10535.367877927349;10568;10589.311694739894;10597.258191926569;4143.150654115899;4209.284421648327;4231.11146659205;4259.74441533509;4262.488501379792;9155.084491550924;9157.77105572366;9166.26669866409;9173.81652366949;9180.403273787182;4500.527444618333;4515.254982927846;4533.7279763213655;4536.3422303765765;4539.160255352705;4027.9597204027586;4040.6565441937764;4044.312466880637;4076.2662720709964;4094.6928980326593;3444.810535420526;3465.54947856777;3481.8259087045544;3496.88099808059;3514.191735900706;1129.949152288149;1131.4060118437885;1133.2294040052843;1147.3230793831679;1156.7512984708328;997.8303688372994;1250.6185613387822;1259.6913756129807;1277.7763891318982;1278.130871007705;6174.598651087665;6841.931580684229;7126.786196414116;7151.141862976476;7159.463040272;587.4008511318432;587.9147523609138;598.3238939997862;611.2909025530363;615.4614712126889;5292.417834038252;5343.759530821122;5344.679319240858;5345.786168553292;5351.705656188869;8653.913460865311;9028.954855225682;9056.36605437613;9063.954680226556;9068.365214435058;8915.063918288615;8953.238974687225;8956.328275379372;8962.058983806737;8988.505632190161;6788.644160493154;6866.485013623898;6887.276835932255;6965.268646792014;6971.721131154798;6421.357864213542;6427.710753016219;6439.227292724903;6446.677666116581;6460.063290822898;7660.463767536442;7915.802104947423;8007.839843202987;8012.879806803019;8025.879611805711;7986.401019923645;8020.83958320842;8029.8394032120195;8046.356291496783;8127.959360203397;6570.737170513126;6602.1747281589805;6649.800505984828;6683.43190828785;6726.428253598303;7139.714411423485;7175.46184036189;7176.282371762782;7178.820529486596;7189.2451292615615;6775.525713200029;6867.381935625817;6894.689738961659;6975.6163411012885;7005.299470052954;6861.794146175623;6918.100646915868;6922.411595014481;6994.930050699428;7004.754833580801;6574.04676321921;6613.371729685672;6627.502937279627;6629.734810607714;6671.199456065303;6114.357992410911;6125.47933425646;6134.447899689049;6138.140660307471;6143.109249158936;6200.472959798287;6266.561340706106;6270.905936410866;6274.215723034621;6281.560290779602 05:47:53 INFO - 2015-11-26 05:47:53,741 DEBUG : BROWSER_OUTPUT: |4;prototype.html;320.3704720224758;327.3796156283842;327.71979957136654;340.45357201691303;341.34802527173105;5825.242718446596;5953.821385358436;6016.849578760533;6023.969880150598;6030.035194368902;5832.066869300902;5930.318013428447;5953.166556682068;5959.255093113361;5968.432998865112;4599.999999999992;4604.746738929358;4611.169989401907;4613.769311534426;4616.33063205835;4380.277254253045;4465;4491.505934347219;4573.199690054244;4622.399088118556;4540.432445944257;4581.106684196583;4594.839180628679;4599.540045995391;4600.608948239404;4590.586847183736;4593.63250939924;4597.494275629678;4600.930986035212;4608.907821843544;4004.2592120457807;4007.6593489553425;4014.0767623446773;4015.578364271739;4020.632984785187;5547.583931205176;5668.263125793662;5671.120976248688;5706.80026199081;5711.771529138871;5286.920696189561;5291.947080529184;5294.894102117974;5298.867528311785;5303.204519322094;6369.745210191587;6370.267419246799;6370.840728981766;6378.362163783631;6379.075034120026;4384.276594361926;4384.364267181241;4394.51660317368;4411.823527058914;4435.844745433894;6441.194850643669;6537.771178008747;6584.308647591982;6584.8024559263295;6597.241317248529;4449.377087207794;4486.820527178941;4499.325101234841;4500.729956202638;4504.076664283814;4544.795484203219;4548.226801443762;4550.203714349976;4553.317002449659;4553.430821147356;5188.9740551297;5278.656887302313;5279;5291.1798671206025;5299.682019078868;6350.587211831216;6386.265579458329;6389.977603583405;6401.647909364937;6404.647744374104;1573.67739613379;1573.945456544118;1574.0948954351293;1577.1246957938224;1577.3138684672203;1060.4591658254346;1066.7813098314828;1072.855164552791;1073.409624706424;1096.13405409727;6207.34822843604;6219.315875253718;6233.283172435137;6234.470070044006;6242.313345531988;6360.618362898241;6504.414602685781;6513.837154071091;6611.206655201407;6615.60306381609;6235.469985051322;6237.345078766755;6253.592941588106;6258.593191442542;6274;3542.734294927891;3566.0906468850276;3586.1751797086818;3606.9639303606627;3607.8196090195383;6651.268360480343;6828.658567071627;6836.829079272959;6862.519623626398;6865.313468653195;6773.221079575814;6789.626570538668;6792.728290868409;6793.184817821893;6794.286599907037;6844.760433384808;6847.315268473212;6847.897281540781;6849.2465828758795;6849.897251541231;6422.6467544285515;6639.6680165992475;6641.236257830411;6645.933540664531;6652.966735166293;6557;6558.901616475661;6577.375149360803;6579.342065793382;6592.208934927839;6833.93166068333;6839.62382068991;6850.177978642595;6851.383375496229;6851.45188384938;4346.456692913386;4431.33529970507;4459.44256967879;4463;4475.619572336388;6564.048213008991;6566.179227596551;6570.868582628416;6571.047198156145;6574.539782215199;6391.680415979089;6393.8721225576155;6407.871842563216;6409.359064093554;6409.647469389229;6264.154339164337;6395.328490508429;6398.776042838478;6458.031295305742;6458.063580780855;6338.683065846597;6340.49276057905;6344.365563443619;6347.079673447417;6348.333424990309;6485.156929599122;6487.870242595216;6488.967555162194;6496.935030649633;6503.577267477599;6102.420270074336;6188.504919606331;6191.318954914866;6307.590006649553;6331.176946996861 05:47:53 INFO - 2015-11-26 05:47:53,742 DEBUG : BROWSER_OUTPUT: |5;yui.html;826.0871736730912;833.9041010283819;836.899572051354;849.9957500212498;865.8701194820778;13251.072424930258;13651.112677675945;13671.248081355521;13699.794503082461;13739.24434156121;12971.805422918664;13102.672433189175;14025.719485610307;14327.140371577712;14400.927995360009;672.156443663202;815.1644564321558;824.5794644731201;825.1707034430419;825.7687847402707;678.7386856060427;810.7689308547066;820.4502983001373;820.5240960243045;822;831.7047448155909;846.1876598465481;846.7036537211957;847.2840449819893;847.8050048488851;681.8397676546008;685.4242436353462;833.8832563441123;839.6431516605448;842.6039761312173;629.1663545801831;629.5498718416309;630.0171732097942;630.7508534129039;631.1542533005749;13573.1856088635;13665.453381864714;13751.037427380088;13755;13756.518521851787;11399.715007124805;11698.473568689344;11733.706657333549;11918.165728399015;12114.333711645855;10203.602388268446;10372.844407333896;10386.688399348031;10390.376577405379;10501.159907207482;3667.7615954962844;3717.033571271462;3724;3743.943840842362;3837.175007373428;49839.00321993576;50431.74784126093;51044.74477627625;51112.233316500286;51157.744211279074;10106.39361638304;10256.07695307426;10315.948420258;10372.273940824221;10423.42671153094;17125.57186070358;17169.484915452307;17177.484675459757;17187;17233.22450489731;7372.078490188726;7404.814879628052;7517.097948246245;7555.73554925575;7704;4206.8527601534415;4251.489821221473;4252.957470425318;4260.2970509866145;4306.095719898856;3098.411301852641;3131.7181453669277;3135.404273188087;3138.2468207630004;3150.212446888278;503.2024241577141;511.9923201151986;512.1677274429053;555.8777069044804;556.8858384031264;586.3608666553478;625.9455931650366;630.2783313106468;649.2598437780933;649.6978904809287;7644.464887457936;7674.808129796688;7737.767866964;7787.961060194662;7879.921200787919;522.1202274167963;536.3644081763123;577.8930897783923;658.5258613798057;659.3867703036127;3860.1507668312925;3947.703922205846;4177.143685544456;4185.393117997874;4337.05511177749;57364;58228;58316.12525812116;58534;58627.41372586305;51661.7416912913;52145.7392713034;53197;54189.729051354494;54918.72540637351;45632.3155152673;46442;46684.5331546687;47031.52968470271;47402.52597473981;8301.252887240178;8351.916480835114;8488.448250863674;8501.234888860032;8530.573471326534;36157;36270.8186459066;36972.44541331936;37199.81400092982;37492.81253593715;45679.314810278534;46876.29685554651;47289.74392304709;47718.52281477141;47902.28146577734;10440.791184176425;10802.297850639998;10873.782524349626;11059.50232239535;11329.546818127183;12002;12134.089943254115;12246.510139594673;12285.262884226975;12316.199447035913;10813.24307298513;11026.834597480884;11194.272372295774;11349.602763903224;11366.829497557708;11050.742697637581;11379.886201137882;11683.707907302385;11793.233439826385;11912.761744764883;11374.374409407563;11511.30932144074;11517.596884109016;11658.067354611441;11897.003258631223;9367.018433088622;9980.650677226553;10004.549795259085;10187;10366.740831479274;8778.561071946506;8845.248153906732;8848.247898928406;9147.679831205876;10012.349197302152 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,881 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545673735__endTimestamp 05:47:53 INFO - 2015-11-26 05:47:53,743 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,747 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:47:53 INFO - 2015-11-26 05:47:53,747 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:47:53 INFO - 2015-11-26 05:47:53,747 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,747 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5432.13 Median:5618.63 stddev:608.00 (10.8%) stddev-sans-first:583.87 05:47:53 INFO - 2015-11-26 05:47:53,747 DEBUG : BROWSER_OUTPUT: Values: 3190.2 3240.8 3287.1 3290.2 3310.8 5230.9 5379.8 5395.6 5420.1 5432.3 5337.6 5345.4 5360.4 5415.5 5507.1 4938.7 4942.7 4965.3 4974.4 5034.5 4892.8 5031.6 5041.6 5072.9 5109.3 4859.0 4964.3 4987.0 5004.0 5031.7 4974.3 4976.6 4991.5 4998.2 5010.9 4869.0 4935.9 4957.8 4964.0 5014.2 5162.8 5207.0 5239.6 5239.6 5241.9 5926.3 5938.1 5948.8 5952.7 5970.6 5959.6 5972.5 5976.5 5978.3 5982.7 5373.7 5376.3 5470.2 5486.3 5486.3 6002.5 6004.3 6008.9 6011.2 6027.3 5690.6 5807.3 5808.1 5811.5 5820.6 5723.3 5845.7 5900.2 5906.6 5908.8 5749.0 5838.2 5905.6 5926.3 5957.7 5759.9 5766.0 5789.5 5806.5 5822.6 4364.1 4460.3 4488.6 4492.0 4496.4 3940.3 4002.0 4005.3 4029.6 4029.7 5521.9 5567.7 5567.8 5571.7 5575.4 5731.4 5785.4 5842.4 5892.7 5901.4 5534.6 5596.6 5613.9 5618.6 5648.0 5106.6 5117.3 5126.5 5129.5 5183.2 5945.7 6070.8 6087.0 6113.7 6114.0 5746.0 5797.9 5803.5 5806.9 5855.2 6038.4 6042.0 6043.8 6044.5 6045.3 5676.5 5713.3 5790.8 5794.8 5803.0 5761.0 5782.6 5800.2 5806.1 5850.6 5733.2 5735.6 5736.7 5739.1 5741.6 5534.9 5615.1 5652.4 5731.0 5740.1 5497.8 5615.4 5617.8 5621.1 5665.9 5772.4 5776.9 5783.8 5788.9 5792.9 5494.7 5595.9 5609.0 5610.1 5650.2 05:47:53 INFO - 2015-11-26 05:47:53,748 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,748 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48555.71 Median:18898.65 stddev:82505.71 (436.6%) stddev-sans-first:82658.44 05:47:53 INFO - 2015-11-26 05:47:53,748 DEBUG : BROWSER_OUTPUT: Values: 554.0 595.8 601.9 611.9 614.6 31468.7 39438.8 39683.6 39728.0 39787.4 41881.8 42185.0 42303.4 42337.6 42403.6 7457.2 7531.7 8776.6 8953.6 8955.2 10231.1 10413.7 10442.4 10449.1 10457.9 10187.5 10234.9 10277.5 10290.6 10294.2 7703.4 7865.8 10163.9 10283.9 10574.0 10192.5 10311.7 10313.3 10314.2 10315.6 39712.0 39932.6 40286.2 40331.8 40344.0 3148.9 3154.9 3173.3 3269.1 3447.2 29424.9 29840.2 30177.0 30444.5 30974.8 3676.8 3733.7 3739.1 3762.5 3825.0 280231.0 282814.0 283543.0 284097.0 284843.0 55251.0 55939.0 56047.7 60705.0 61254.1 49767.5 70364.6 71129.0 71408.0 71484.3 15493.9 15750.1 15779.3 15862.7 15890.0 12290.4 12296.9 12298.8 12307.9 12316.8 20078.9 20322.7 20372.7 20431.7 20444.2 7904.2 7966.2 7977.8 8020.7 8118.7 883.7 961.5 1003.2 1039.2 1066.9 28678.7 29053.0 29393.4 29395.6 29430.7 1008.7 1010.9 1041.0 1077.2 1121.8 16194.0 17088.5 17245.0 17289.5 17364.7 392952.0 398144.0 398921.0 399062.0 399358.0 54886.0 55410.4 55430.0 55506.2 55535.4 1341.7 1348.3 1356.3 1361.8 1361.8 28908.0 29812.4 30200.4 30410.0 32157.8 42977.6 43923.8 48975.5 49597.0 50441.2 239512.0 241114.0 241167.0 241608.0 244308.0 37331.6 38288.4 38715.7 38767.8 38775.8 41308.2 41405.6 44489.3 44685.8 44961.0 37139.4 37551.4 41375.2 41862.8 42675.6 36411.6 36791.8 37203.8 37409.4 37827.2 36598.5 36946.6 37623.0 37630.6 37654.0 31828.7 32567.5 33726.7 34206.1 34350.1 25565.5 25768.4 25790.5 25908.6 26062.3 05:47:53 INFO - 2015-11-26 05:47:53,748 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,748 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28922.23 Median:4083.01 stddev:91176.19 (2233.1%) stddev-sans-first:91406.70 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: Values: 338.6 339.9 346.6 346.9 348.2 13634.0 13986.2 16234.5 16345.7 17052.6 13273.7 15363.5 15384.3 15408.1 15985.2 4695.3 4747.7 4792.5 4794.9 4804.3 3649.1 3775.7 4828.0 4856.6 5232.9 5251.8 5264.8 5310.2 5329.7 5349.3 5285.8 5287.1 5378.7 5805.6 5807.7 5172.6 5173.9 5185.6 5189.6 5190.0 19269.8 19930.2 20155.6 20169.6 20834.4 2059.9 2120.9 2198.3 2258.9 2403.7 9401.0 9522.7 9679.9 9683.1 9697.5 1747.1 1810.2 1817.7 1845.5 1852.3 61158.4 61651.0 61689.4 61708.0 63497.7 9868.0 10148.7 10204.8 10231.6 10278.0 13231.0 13243.0 13303.1 13348.5 13359.5 7618.0 7665.5 7679.8 7816.5 7830.3 7379.4 7506.4 7552.3 7554.9 7635.1 4390.3 4395.0 4398.8 4404.9 4413.4 1707.1 1748.4 1762.5 1769.6 1826.7 802.5 935.6 938.6 1031.0 1041.7 16054.0 16726.0 16825.5 17033.4 17181.2 764.8 903.9 932.6 934.5 941.5 8491.7 9326.4 9604.2 9607.7 9622.1 541789.0 542152.0 550830.0 574229.0 579306.0 77424.6 78522.6 79205.0 79283.0 79443.0 1086.8 1089.3 1105.6 1113.9 1113.9 14415.3 14492.6 14657.3 14831.9 14848.9 33442.2 33490.3 33683.3 33814.0 33869.2 44591.3 44680.3 46225.5 46459.1 48953.3 8815.5 9315.0 9644.2 9695.5 9790.9 11036.3 11218.5 11415.3 11474.1 14025.6 13724.9 13828.5 14149.2 14457.5 14799.7 14047.0 14143.4 14150.9 14224.2 14678.2 14172.5 14439.9 14445.0 14767.2 15094.8 7958.4 8173.7 8646.7 8940.6 9033.3 9787.8 9916.2 11874.6 13542.5 13744.9 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5250.64 Median:6233.52 stddev:2752.87 (44.2%) stddev-sans-first:2744.82 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: Values: 1330.8 1474.6 1522.3 1668.2 1778.9 7018.9 7537.7 7854.5 7860.1 7864.3 7606.6 7753.1 7814.1 7832.5 7845.3 1969.2 2014.7 2229.0 2235.6 2266.4 2238.2 2241.5 2242.5 2243.6 2245.9 2185.5 2240.6 2241.3 2241.8 2245.0 1939.7 2006.0 2186.3 2225.3 2227.8 2221.7 2232.3 2251.4 2254.3 2258.5 7536.3 7546.3 7582.5 7589.8 7599.6 4524.8 4589.0 4593.6 4598.4 4625.5 2909.6 2925.3 2971.9 2972.4 3018.5 883.5 887.5 900.2 907.6 921.0 10416.7 10535.4 10568.0 10589.3 10597.3 4143.2 4209.3 4231.1 4259.7 4262.5 9155.1 9157.8 9166.3 9173.8 9180.4 4500.5 4515.3 4533.7 4536.3 4539.2 4028.0 4040.7 4044.3 4076.3 4094.7 3444.8 3465.5 3481.8 3496.9 3514.2 1129.9 1131.4 1133.2 1147.3 1156.8 997.8 1250.6 1259.7 1277.8 1278.1 6174.6 6841.9 7126.8 7151.1 7159.5 587.4 587.9 598.3 611.3 615.5 5292.4 5343.8 5344.7 5345.8 5351.7 8653.9 9029.0 9056.4 9064.0 9068.4 8915.1 8953.2 8956.3 8962.1 8988.5 6788.6 6866.5 6887.3 6965.3 6971.7 6421.4 6427.7 6439.2 6446.7 6460.1 7660.5 7915.8 8007.8 8012.9 8025.9 7986.4 8020.8 8029.8 8046.4 8128.0 6570.7 6602.2 6649.8 6683.4 6726.4 7139.7 7175.5 7176.3 7178.8 7189.2 6775.5 6867.4 6894.7 6975.6 7005.3 6861.8 6918.1 6922.4 6994.9 7004.8 6574.0 6613.4 6627.5 6629.7 6671.2 6114.4 6125.5 6134.4 6138.1 6143.1 6200.5 6266.6 6270.9 6274.2 6281.6 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5335.31 Median:6145.46 stddev:1620.78 (26.4%) stddev-sans-first:1581.01 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: Values: 320.4 327.4 327.7 340.5 341.3 5825.2 5953.8 6016.8 6024.0 6030.0 5832.1 5930.3 5953.2 5959.3 5968.4 4600.0 4604.7 4611.2 4613.8 4616.3 4380.3 4465.0 4491.5 4573.2 4622.4 4540.4 4581.1 4594.8 4599.5 4600.6 4590.6 4593.6 4597.5 4600.9 4608.9 4004.3 4007.7 4014.1 4015.6 4020.6 5547.6 5668.3 5671.1 5706.8 5711.8 5286.9 5291.9 5294.9 5298.9 5303.2 6369.7 6370.3 6370.8 6378.4 6379.1 4384.3 4384.4 4394.5 4411.8 4435.8 6441.2 6537.8 6584.3 6584.8 6597.2 4449.4 4486.8 4499.3 4500.7 4504.1 4544.8 4548.2 4550.2 4553.3 4553.4 5189.0 5278.7 5279.0 5291.2 5299.7 6350.6 6386.3 6390.0 6401.6 6404.6 1573.7 1573.9 1574.1 1577.1 1577.3 1060.5 1066.8 1072.9 1073.4 1096.1 6207.3 6219.3 6233.3 6234.5 6242.3 6360.6 6504.4 6513.8 6611.2 6615.6 6235.5 6237.3 6253.6 6258.6 6274.0 3542.7 3566.1 3586.2 3607.0 3607.8 6651.3 6828.7 6836.8 6862.5 6865.3 6773.2 6789.6 6792.7 6793.2 6794.3 6844.8 6847.3 6847.9 6849.2 6849.9 6422.6 6639.7 6641.2 6645.9 6653.0 6557.0 6558.9 6577.4 6579.3 6592.2 6833.9 6839.6 6850.2 6851.4 6851.5 4346.5 4431.3 4459.4 4463.0 4475.6 6564.0 6566.2 6570.9 6571.0 6574.5 6391.7 6393.9 6407.9 6409.4 6409.6 6264.2 6395.3 6398.8 6458.0 6458.1 6338.7 6340.5 6344.4 6347.1 6348.3 6485.2 6487.9 6489.0 6496.9 6503.6 6102.4 6188.5 6191.3 6307.6 6331.2 05:47:53 INFO - 2015-11-26 05:47:53,750 DEBUG : BROWSER_OUTPUT: 05:47:53 INFO - 2015-11-26 05:47:53,751 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14034.47 Median:46060.66 stddev:16510.69 (35.8%) stddev-sans-first:16527.21 05:47:53 INFO - 2015-11-26 05:47:53,751 DEBUG : BROWSER_OUTPUT: Values: 826.1 833.9 836.9 850.0 865.9 13251.1 13651.1 13671.2 13699.8 13739.2 12971.8 13102.7 14025.7 14327.1 14400.9 672.2 815.2 824.6 825.2 825.8 678.7 810.8 820.5 820.5 822.0 831.7 846.2 846.7 847.3 847.8 681.8 685.4 833.9 839.6 842.6 629.2 629.5 630.0 630.8 631.2 13573.2 13665.5 13751.0 13755.0 13756.5 11399.7 11698.5 11733.7 11918.2 12114.3 10203.6 10372.8 10386.7 10390.4 10501.2 3667.8 3717.0 3724.0 3743.9 3837.2 49839.0 50431.7 51044.7 51112.2 51157.7 10106.4 10256.1 10315.9 10372.3 10423.4 17125.6 17169.5 17177.5 17187.0 17233.2 7372.1 7404.8 7517.1 7555.7 7704.0 4206.9 4251.5 4253.0 4260.3 4306.1 3098.4 3131.7 3135.4 3138.2 3150.2 503.2 512.0 512.2 555.9 556.9 586.4 625.9 630.3 649.3 649.7 7644.5 7674.8 7737.8 7788.0 7879.9 522.1 536.4 577.9 658.5 659.4 3860.2 3947.7 4177.1 4185.4 4337.1 57364.0 58228.0 58316.1 58534.0 58627.4 51661.7 52145.7 53197.0 54189.7 54918.7 45632.3 46442.0 46684.5 47031.5 47402.5 8301.3 8351.9 8488.4 8501.2 8530.6 36157.0 36270.8 36972.4 37199.8 37492.8 45679.3 46876.3 47289.7 47718.5 47902.3 10440.8 10802.3 10873.8 11059.5 11329.5 12002.0 12134.1 12246.5 12285.3 12316.2 10813.2 11026.8 11194.3 11349.6 11366.8 11050.7 11379.9 11683.7 11793.2 11912.8 11374.4 11511.3 11517.6 11658.1 11897.0 9367.0 9980.7 10004.5 10187.0 10366.7 8778.6 8845.2 8848.2 9147.7 10012.3 05:47:53 INFO - 2015-11-26 05:47:53,752 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:47:53 INFO - 2015-11-26 05:47:53,752 DEBUG : BROWSER_OUTPUT: 05:47:54 INFO - 2015-11-26 05:47:54,413 INFO : Browser exited with error code: 0 05:47:54 INFO - 2015-11-26 05:47:54,436 INFO : Completed test dromaeo_css (00:18:33) 05:47:54 INFO - 2015-11-26 05:47:54,436 INFO : Starting test kraken 05:47:54 INFO - 2015-11-26 05:47:54,436 DEBUG : operating with platform_type : linux_ 05:47:54 INFO - 2015-11-26 05:47:54,437 INFO : Initialising browser for kraken test... 05:47:54 INFO - 2015-11-26 05:47:54,443 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp75DZUM/profile http://localhost:41162/getInfo.html 05:47:58 INFO - 2015-11-26 05:47:58,419 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:47:58 INFO - 2015-11-26 05:47:58,420 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:47:58 INFO - 2015-11-26 05:47:58,420 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:47:58 INFO - 2015-11-26 05:47:58,420 DEBUG : BROWSER_OUTPUT: __metrics 05:47:58 INFO - 2015-11-26 05:47:58,488 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] 05:47:59 INFO - 2015-11-26 05:47:59,812 INFO : Browser initialized. 05:47:59 INFO - 2015-11-26 05:47:59,813 INFO : Running cycle 1/1 for kraken test... 05:47:59 INFO - 2015-11-26 05:47:59,813 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp75DZUM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:48:11 INFO - 2015-11-26 05:48:11,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 145891328 05:48:11 INFO - 2015-11-26 05:48:11,492 DEBUG : BROWSER_OUTPUT: 05:48:13 INFO - 2015-11-26 05:48:13,926 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:14 INFO - 2015-11-26 05:48:14,507 DEBUG : BROWSER_OUTPUT: RSS: Main: 182665216 05:48:14 INFO - 2015-11-26 05:48:14,507 DEBUG : BROWSER_OUTPUT: 05:48:17 INFO - 2015-11-26 05:48:17,093 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-dft) 05:48:17 INFO - 2015-11-26 05:48:17,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 172097536 05:48:17 INFO - 2015-11-26 05:48:17,662 DEBUG : BROWSER_OUTPUT: 05:48:20 INFO - 2015-11-26 05:48:20,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-fft) 05:48:21 INFO - 2015-11-26 05:48:21,162 DEBUG : BROWSER_OUTPUT: RSS: Main: 157556736 05:48:21 INFO - 2015-11-26 05:48:21,162 DEBUG : BROWSER_OUTPUT: 05:48:23 INFO - 2015-11-26 05:48:23,125 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41162/tests/kraken/driver.html?testName=audio-oscillator) 05:48:23 INFO - 2015-11-26 05:48:23,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 160493568 05:48:23 INFO - 2015-11-26 05:48:23,693 DEBUG : BROWSER_OUTPUT: 05:48:25 INFO - 2015-11-26 05:48:25,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:26 INFO - 2015-11-26 05:48:26,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 162013184 05:48:26 INFO - 2015-11-26 05:48:26,436 DEBUG : BROWSER_OUTPUT: 05:48:29 INFO - 2015-11-26 05:48:29,319 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:29 INFO - 2015-11-26 05:48:29,888 DEBUG : BROWSER_OUTPUT: RSS: Main: 160727040 05:48:29 INFO - 2015-11-26 05:48:29,888 DEBUG : BROWSER_OUTPUT: 05:48:33 INFO - 2015-11-26 05:48:33,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41162/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:33 INFO - 2015-11-26 05:48:33,797 DEBUG : BROWSER_OUTPUT: RSS: Main: 163188736 05:48:33 INFO - 2015-11-26 05:48:33,798 DEBUG : BROWSER_OUTPUT: 05:48:36 INFO - 2015-11-26 05:48:36,520 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41162/tests/kraken/driver.html?testName=json-parse-financial) 05:48:37 INFO - 2015-11-26 05:48:37,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 163934208 05:48:37 INFO - 2015-11-26 05:48:37,093 DEBUG : BROWSER_OUTPUT: 05:48:38 INFO - 2015-11-26 05:48:38,626 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41162/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:39 INFO - 2015-11-26 05:48:39,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 174882816 05:48:39 INFO - 2015-11-26 05:48:39,187 DEBUG : BROWSER_OUTPUT: 05:48:40 INFO - 2015-11-26 05:48:40,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:48:41 INFO - 2015-11-26 05:48:41,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 173600768 05:48:41 INFO - 2015-11-26 05:48:41,585 DEBUG : BROWSER_OUTPUT: 05:48:43 INFO - 2015-11-26 05:48:43,649 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:48:44 INFO - 2015-11-26 05:48:44,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 182341632 05:48:44 INFO - 2015-11-26 05:48:44,221 DEBUG : BROWSER_OUTPUT: 05:48:46 INFO - 2015-11-26 05:48:46,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:48:47 INFO - 2015-11-26 05:48:47,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 190603264 05:48:47 INFO - 2015-11-26 05:48:47,184 DEBUG : BROWSER_OUTPUT: 05:48:49 INFO - 2015-11-26 05:48:49,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:48:50 INFO - 2015-11-26 05:48:50,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 188313600 05:48:50 INFO - 2015-11-26 05:48:50,362 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,037 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41162/tests/kraken/driver.html?testName=ai-astar) 05:48:52 INFO - 2015-11-26 05:48:52,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 247476224 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:48:52 INFO - 2015-11-26 05:48:52,295 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;104;109;110;111;99;109;109;100 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;125;157;161;125;152;149;126;173;125 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;160;162;162;160;159;161;162;161 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;72;79;93;71;74;71;107;89;71 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;147;92;92;92;145;92;92;134 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;109;109;110;109;110;109 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;145;148;145;145;145;145;145 05:48:52 INFO - 2015-11-26 05:48:52,296 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;95;96;96;96;96;95 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;58;58;57;58;57;57;61;56;55 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;71;69;70;70;70;70;70;70;70;70 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;92;82;84;85;85;84;84;85;94;94 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;112;118;112;126;124;123;116;119;129 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;169;154;150;150;151;161;150;151;144 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;67;67;67;67;69;67;66;66;67 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,907 05:48:52 INFO - 2015-11-26 05:48:52,297 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545732289__endTimestamp 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.10 Median:110.00 stddev:4.43 (4.0%) stddev-sans-first:4.58 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 104.0 109.0 110.0 111.0 99.0 109.0 109.0 100.0 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,298 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:150.50 stddev:17.62 (11.7%) stddev-sans-first:18.69 05:48:52 INFO - 2015-11-26 05:48:52,299 DEBUG : BROWSER_OUTPUT: Values: 144.0 125.0 157.0 161.0 125.0 152.0 149.0 126.0 173.0 125.0 05:48:52 INFO - 2015-11-26 05:48:52,299 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.09 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 160.0 162.0 162.0 160.0 159.0 161.0 162.0 161.0 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.90 Median:73.00 stddev:12.41 (17.0%) stddev-sans-first:12.83 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 79.0 93.0 71.0 74.0 71.0 107.0 89.0 71.0 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.10 Median:92.00 stddev:24.31 (26.4%) stddev-sans-first:25.16 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 147.0 92.0 92.0 92.0 145.0 92.0 92.0 134.0 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,300 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 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 109.0 109.0 110.0 109.0 110.0 109.0 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 145.0 148.0 145.0 145.0 145.0 145.0 145.0 05:48:52 INFO - 2015-11-26 05:48:52,300 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 95.0 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.58 (2.7%) stddev-sans-first:1.67 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: Values: 58.0 58.0 58.0 57.0 58.0 57.0 57.0 61.0 56.0 55.0 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: Values: 71.0 69.0 70.0 70.0 70.0 70.0 70.0 70.0 70.0 70.0 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,301 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:85.00 stddev:4.56 (5.4%) stddev-sans-first:4.44 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: Values: 92.0 82.0 84.0 85.0 85.0 84.0 84.0 85.0 94.0 94.0 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.20 Median:123.00 stddev:5.77 (4.7%) stddev-sans-first:6.03 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: Values: 123.0 112.0 118.0 112.0 126.0 124.0 123.0 116.0 119.0 129.0 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:151.00 stddev:7.98 (5.3%) stddev-sans-first:7.38 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: Values: 141.0 169.0 154.0 150.0 150.0 151.0 161.0 150.0 151.0 144.0 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 67.0 67.0 67.0 69.0 67.0 66.0 66.0 67.0 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:48:52 INFO - 2015-11-26 05:48:52,302 DEBUG : BROWSER_OUTPUT: 05:48:53 INFO - 2015-11-26 05:48:53,129 INFO : Browser exited with error code: 0 05:48:53 INFO - 2015-11-26 05:48:53,178 INFO : Completed test kraken (00:00:58) 05:48:53 INFO - 2015-11-26 05:48:53,178 INFO : Starting test v8_7 05:48:53 INFO - 2015-11-26 05:48:53,178 DEBUG : operating with platform_type : linux_ 05:48:53 INFO - 2015-11-26 05:48:53,178 INFO : Initialising browser for v8_7 test... 05:48:53 INFO - 2015-11-26 05:48:53,184 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp__SOXE/profile http://localhost:41162/getInfo.html 05:48:57 INFO - 2015-11-26 05:48:57,161 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:48:57 INFO - 2015-11-26 05:48:57,161 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:48:57 INFO - 2015-11-26 05:48:57,161 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:48:57 INFO - 2015-11-26 05:48:57,161 DEBUG : BROWSER_OUTPUT: __metrics 05:48:57 INFO - 2015-11-26 05:48:57,324 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] 05:48:58 INFO - 2015-11-26 05:48:58,604 INFO : Browser initialized. 05:48:58 INFO - 2015-11-26 05:48:58,604 INFO : Running cycle 1/1 for v8_7 test... 05:48:58 INFO - 2015-11-26 05:48:58,605 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp__SOXE/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:49:10 INFO - 2015-11-26 05:49:10,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 145797120 05:49:10 INFO - 2015-11-26 05:49:10,296 DEBUG : BROWSER_OUTPUT: 05:49:31 INFO - 2015-11-26 05:49:31,748 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41162/tests/v8_7/run.html (next: http://localhost:41162/tests/v8_7/run.html) 05:49:31 INFO - 2015-11-26 05:49:31,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 624308224 05:49:31 INFO - 2015-11-26 05:49:31,999 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:31,999 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:32 INFO - 2015-11-26 05:49:31,999 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:32 INFO - 2015-11-26 05:49:31,999 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:32 INFO - 2015-11-26 05:49:32,000 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:32 INFO - 2015-11-26 05:49:32,000 DEBUG : BROWSER_OUTPUT: |0;Richards;164.74464579901152 05:49:32 INFO - 2015-11-26 05:49:32,000 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.43904335990666 05:49:32 INFO - 2015-11-26 05:49:32,000 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.1716396703609 05:49:32 INFO - 2015-11-26 05:49:32,001 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5133.333333333333 05:49:32 INFO - 2015-11-26 05:49:32,001 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1123.5955056179776 05:49:32 INFO - 2015-11-26 05:49:32,001 DEBUG : BROWSER_OUTPUT: |5;Earley;777.6049766718507 05:49:32 INFO - 2015-11-26 05:49:32,001 DEBUG : BROWSER_OUTPUT: |6;Boyer;11858.823529411764 05:49:32 INFO - 2015-11-26 05:49:32,002 DEBUG : BROWSER_OUTPUT: |7;RegExp;25525 05:49:32 INFO - 2015-11-26 05:49:32,002 DEBUG : BROWSER_OUTPUT: |8;Splay;654.296875 05:49:32 INFO - 2015-11-26 05:49:32,002 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 05:49:32 INFO - 2015-11-26 05:49:32,002 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:32 INFO - 2015-11-26 05:49:32,003 DEBUG : BROWSER_OUTPUT: __startTimestamp1448545771996__endTimestamp 05:49:32 INFO - 2015-11-26 05:49:32,003 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,003 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:32 INFO - 2015-11-26 05:49:32,003 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:49:32 INFO - 2015-11-26 05:49:32,004 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,004 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,004 DEBUG : BROWSER_OUTPUT: Values: 164.7 05:49:32 INFO - 2015-11-26 05:49:32,004 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,004 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.44 Median:194.44 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: Values: 194.4 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.17 Median:284.17 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: Values: 284.2 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5133.33 Median:5133.33 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: Values: 5133.3 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1123.60 Median:1123.60 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: Values: 1123.6 05:49:32 INFO - 2015-11-26 05:49:32,005 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:777.60 Median:777.60 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: Values: 777.6 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11858.82 Median:11858.82 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: Values: 11858.8 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25525.00 Median:25525.00 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,006 DEBUG : BROWSER_OUTPUT: Values: 25525.0 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:654.30 Median:654.30 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: Values: 654.3 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: Values: 4850.2 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:32 INFO - 2015-11-26 05:49:32,007 DEBUG : BROWSER_OUTPUT: 05:49:32 INFO - 2015-11-26 05:49:32,845 INFO : Browser exited with error code: 0 05:49:32 INFO - 2015-11-26 05:49:32,859 INFO : Completed test v8_7 (00:00:39) 05:49:32 INFO - 2015-11-26 05:49:32,955 INFO : Completed test suite (00:20:11) 05:49:32 INFO - 2015-11-26 05:49:32,955 DEBUG : Working with test: dromaeo_css 05:49:32 INFO - 2015-11-26 05:49:32,955 DEBUG : Generating results file: dromaeo_css 05:49:32 INFO - 2015-11-26 05:49:32,956 DEBUG : Working with test: kraken 05:49:32 INFO - 2015-11-26 05:49:32,956 DEBUG : Generating results file: kraken 05:49:32 INFO - 2015-11-26 05:49:32,957 INFO : javascript benchmark 05:49:32 INFO - 2015-11-26 05:49:32,957 DEBUG : Working with test: v8_7 05:49:32 INFO - 2015-11-26 05:49:32,957 DEBUG : Generating results file: v8_7 05:49:32 INFO - 2015-11-26 05:49:32,957 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:33 INFO - 2015-11-26 05:49:33,014 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,140,35]] 05:49:33 INFO - 2015-11-26 05:49:33,014 DEBUG : process_Request line: dromaeo_css 6966.98 graph.html#tests=[[72,140,35]] 05:49:33 INFO - 2015-11-26 05:49:33,015 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:33 INFO - 2015-11-26 05:49:33,047 DEBUG : process_Request line: kraken 1503.00 graph.html#tests=[[232,140,35]] 05:49:33 INFO - 2015-11-26 05:49:33,047 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:33 INFO - 2015-11-26 05:49:33,077 DEBUG : process_Request line: v8_7 20897.08 graph.html#tests=[[230,140,35]] 05:49:33 INFO - 2015-11-26 05:49:33,082 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3190.218396492859, 3240.7569432292585, 3287.0631869917065, 3290.2432440538673, 3310.817905015225, 5230.895382092356, 5379.8386048418515, 5395.649282796616, 5420.051490989076, 5432.293801805769, 5337.626366154371, 5345.358556973159, 5360.410354860962, 5415.512603865652, 5507.146392309189, 4938.678985865925, 4942.728149951751, 4965.3048573199785, 4974.403071631409, 5034.521720436553, 4892.804287828482, 5031.647784655075, 5041.647084704072, 5072.873178170538, 5109.284700141983, 4858.975705121469, 4964.304997300381, 4987.002599480118, 5004.024215278022, 5031.6729412588065, 4974.328465657126, 4976.576990955773, 4991.450940396553, 4998.175301075318, 5010.874728131789, 4868.975655121737, 4935.851924442272, 4957.752112394402, 4964.032013757319, 5014.222795466709, 5162.767675454614, 5207.010667973111, 5239.5546378557865, 5239.580833533309, 5241.895162096736, 5926.318473375531, 5938.10928360745, 5948.821535353946, 5952.702364881782, 5970.582059255854, 5959.582829201958, 5972.522198224132, 5976.491998180117, 5978.342382337939, 5982.670953097578, 5373.731313434313, 5376.327959005124, 5470.152126420411, 5486.31421072362, 5486.341639003305, 6002.519798416116, 6004.3395226525045, 6008.909866352008, 6011.158437818709, 6027.276726792813, 5690.573207009408, 5807.332156802022, 5808.128780682847, 5811.506021988093, 5820.563457740686, 5723.3418156912485, 5845.7369418376275, 5900.203472531153, 5906.645601263938, 5908.822735317947, 5748.971255143698, 5838.211841401441, 5905.616134951214, 5926.348101708809, 5957.672328022, 5759.8560035999435, 5765.971170144123, 5789.507891829242, 5806.477417032487, 5822.621529600562, 4364.10535840152, 4460.286354183315, 4488.618467430239, 4492.011757413364, 4496.437945256843, 3940.310445671996, 4002.039510517455, 4005.2990726622725, 4029.5970402960056, 4029.7179197456485, 5521.944780552223, 5567.693776842312, 5567.805126820542, 5571.665700057928, 5575.386707462176, 5731.398203188605, 5785.421457854265, 5842.386549412336, 5892.705364731746, 5901.350851406428, 5534.640248383842, 5596.608237423341, 5613.915791263134, 5618.634788738719, 5648.039833228361, 5106.591472682177, 5117.334746483008, 5126.512981266774, 5129.512696293846, 5183.222516622537, 5945.672987985616, 6070.785842831539, 6086.995645718495, 6113.663748493786, 6114.0, 5746.0, 5797.8550536236935, 5803.47768700819, 5806.941930580809, 5855.2095467113095, 6038.365971572922, 6042.0332746760305, 6043.7884674036195, 6044.486218671462, 6045.304789949212, 5676.517496012885, 5713.342965559014, 5790.768369265183, 5794.768209271582, 5802.970985145047, 5761.0, 5782.595218334676, 5800.187973683604, 5806.1000544915605, 5850.5904586678525, 5733.168690539932, 5735.627184233012, 5736.6558006519745, 5739.053056245672, 5741.569382296261, 5534.944650553443, 5615.129654903497, 5652.360815363499, 5731.0, 5740.0815869460685, 5497.835064948058, 5615.382307946041, 5617.831465056055, 5621.072523033654, 5665.886682266414, 5772.422757724194, 5776.9133462998925, 5783.826485205451, 5788.855278618068, 5792.94207057924, 5494.697791621499, 5595.860103497445, 5609.0, 5610.13042978339, 5650.237217975685], "unit": "ms", "name": "dojo.html", "value": 5392.3149100582805}, {"lowerIsBetter": false, "replicates": [554.0083250980745, 595.81529725785, 601.9157317975485, 611.8715069835337, 614.5728718540611, 31468.685313146863, 39438.802805985964, 39683.60316396835, 39728.0, 39787.40318895219, 41881.790591047, 42185.0, 42303.365449518285, 42337.57662423375, 42403.57596424035, 7457.179710231871, 7531.736389226378, 8776.60505277264, 8953.641854325819, 8955.19403253707, 10231.130353919925, 10413.739656508573, 10442.425666588335, 10449.059584637382, 10457.895421045807, 10187.54156062979, 10234.89765102347, 10277.537510812032, 10290.588376464932, 10294.17646588271, 7703.383729301699, 7865.842683146362, 10163.949180254089, 10283.948580257125, 10574.0, 10192.490375481271, 10311.690649280534, 10313.329633573869, 10314.226433017553, 10315.587376504931, 39712.0, 39932.600673993176, 40286.194276114315, 40331.7983410084, 40344.0, 3148.921276968071, 3154.921126971821, 3173.2701478660038, 3269.0683155300862, 3447.1554469155217, 29424.852875735698, 29840.16338188521, 30177.0, 30444.54333185004, 30974.845125774453, 3676.7610105343333, 3733.6826369758605, 3739.083924438503, 3762.49206357141, 3825.024618722236, 280231.0, 282814.0, 283543.0, 284097.0, 284843.0, 55251.0, 55939.0, 56047.719761400935, 60705.0, 61254.081188782206, 49767.50232497701, 70364.64817675878, 71129.0, 71408.0, 71484.2851571488, 15493.922530387501, 15750.133742644264, 15779.28993195309, 15862.68274634524, 15890.0, 12290.385480725929, 12296.938515307365, 12298.815517767242, 12307.938460307641, 12316.81524777129, 20078.89960550217, 20322.695159572617, 20372.69440958387, 20431.693524597144, 20444.182232710526, 7904.170062143392, 7966.203379661987, 7977.760667179994, 8020.679172833138, 8118.718906715236, 883.6774577279247, 961.4567769210374, 1003.1924300937815, 1039.2361614213546, 1066.8719753629614, 28678.653950763186, 29053.0, 29393.412131757246, 29395.55906661402, 29430.705692942796, 1008.6570566007592, 1010.9435639756325, 1040.9850395864123, 1077.224398433127, 1121.8149005414016, 16194.0, 17088.487345379657, 17245.0, 17289.48131556055, 17364.652706946043, 392952.0, 398144.0, 398921.0, 399062.0, 399358.0, 54886.0, 55410.445895541336, 55430.0, 55506.16740748892, 55535.444645553835, 1341.6578772413168, 1348.2786709110776, 1356.3286173344259, 1361.761691703948, 1361.7889227169806, 28908.0, 29812.403751925274, 30200.395992080474, 30410.0, 32157.839210803795, 42977.57022429861, 43923.78038109789, 48975.510244897094, 49597.0, 50441.24338135005, 239512.0, 241114.0, 241167.0, 241608.0, 244308.0, 37331.626683732815, 38288.42567361548, 38715.677059376525, 38767.80616096901, 38775.806120970345, 41308.1738365237, 41405.58594414017, 44489.332660010776, 44685.77657111824, 44961.0, 37139.442908356934, 37551.43672844855, 41375.172496550505, 41862.79068604759, 42675.57324426716, 36411.63588364189, 36791.81604091962, 37203.813980929925, 37409.43885841769, 37827.24345513129, 36598.45102323414, 36946.63053369432, 37623.0, 37630.62369376271, 37653.987680986, 31828.68171318257, 32567.511487327236, 33726.66273337235, 34206.14484637904, 34350.14124646904, 25565.488690226462, 25768.355791105372, 25790.484190316463, 25908.611370829076, 26062.34844128912], "unit": "ms", "name": "ext.html", "value": 19257.644828146284}, {"lowerIsBetter": false, "replicates": [338.6122889291761, 339.9490076488525, 346.61179478983524, 346.87859249262755, 348.22867348822354, 13634.0, 13986.230757308344, 16234.51296461105, 16345.673086538261, 17052.573685657848, 13273.734525309488, 15363.539093827176, 15384.307706153222, 15408.07551546908, 15985.200739962984, 4695.3191787190835, 4747.667663263573, 4792.520747925197, 4794.88012799681, 4804.279358096296, 3649.051246675857, 3775.7168212384045, 4827.975860120694, 4856.6357523185725, 5232.947670523303, 5251.76367063483, 5264.763085661135, 5310.15037593985, 5329.680219186842, 5349.277847490597, 5285.814996475124, 5287.127623942064, 5378.650387724785, 5805.564582656276, 5807.709614519299, 5172.612054095958, 5173.870653233699, 5185.585153187736, 5189.636725429222, 5190.0, 19269.80730192708, 19930.209770632122, 20155.596888062155, 20169.596608067903, 20834.374968750963, 2059.855810093294, 2120.925767598142, 2198.318521258415, 2258.8757618330983, 2403.687520622308, 9400.952995235048, 9522.714318570384, 9679.854802177973, 9683.128518433376, 9697.51512424383, 1747.1264367816093, 1810.2035104554086, 1817.7000794868964, 1845.4832646858902, 1852.3053854804448, 61158.38841611571, 61651.0, 61689.38310616881, 61708.0, 63497.682511587605, 9867.950660246654, 10148.695539133838, 10204.795904081877, 10231.590736370461, 10278.0, 13231.007674424456, 13243.0, 13303.13529620591, 13348.532801351907, 13359.465621375231, 7617.961910190525, 7665.540067595962, 7679.846403071907, 7816.49192802466, 7830.256125668053, 7379.409647228209, 7506.437017223621, 7552.320291173711, 7554.924450755422, 7635.121960974448, 4390.297552391602, 4395.0, 4398.802053907518, 4404.933925991113, 4413.404190434315, 1707.1293640243566, 1748.3793253395115, 1762.5153082902048, 1769.619531800669, 1826.662067517487, 802.4623502253504, 935.6179741836912, 938.6151677812064, 1030.9688719050982, 1041.656253436364, 16054.0, 16726.0, 16825.495235142964, 17033.444983252364, 17181.226844791763, 764.7514557768748, 903.8807274750037, 932.6455946740195, 934.4766930518931, 941.4774799985937, 8491.660333586711, 9326.440398591498, 9604.231661467065, 9607.663731769355, 9622.085901839315, 541789.0, 542152.0, 550830.0, 574229.0, 579306.0, 77424.61287693639, 78522.60738696385, 79205.0, 79283.0, 79443.0, 1086.7554800170058, 1089.3409487260094, 1105.6351404036648, 1113.9387333696725, 1113.9443027848574, 14415.279236038366, 14492.637684057561, 14657.267136643339, 14831.851681483047, 14848.925755371154, 33442.16394590155, 33490.33019339648, 33683.326333473684, 33814.0, 33869.15327116842, 44591.33113003243, 44680.32979505375, 46225.53774462347, 46459.07081858276, 48953.265701015225, 8815.471071735716, 9315.0, 9644.228461722905, 9695.51522423862, 9790.902090979, 11036.282641628268, 11218.495167717636, 11415.315081095161, 11474.082073433938, 14025.579232623038, 13724.862751372359, 13828.516001939885, 14149.221792801494, 14457.493987710379, 14799.704005919606, 14047.0, 14143.363548640542, 14150.858491414954, 14224.217668028357, 14678.192699401636, 14172.503962361268, 14439.855601444271, 14445.0, 14767.187804670846, 15094.773578396112, 7958.403119765925, 8173.673053078048, 8646.697365592174, 8940.552972351226, 9033.322500812334, 9787.80424391494, 9916.206703463562, 11874.643760687193, 13542.458301668217, 13744.862551374757], "unit": "ms", "name": "jquery.html", "value": 8558.628405713023}, {"lowerIsBetter": false, "replicates": [1330.773768459362, 1474.5502621700384, 1522.322566457926, 1668.1825905306403, 1778.8754787164896, 7018.894716579249, 7537.736179233728, 7854.450188486809, 7860.135385107634, 7864.252895974881, 7606.619669016541, 7753.069631644196, 7814.062312522491, 7832.451728379016, 7845.293923546879, 1969.1827891425041, 2014.717939488473, 2228.999999999996, 2235.575240704269, 2266.422062374092, 2238.227811405071, 2241.529278851443, 2242.4618091657967, 2243.607368710477, 2245.8764767937755, 2185.508260641359, 2240.574290884727, 2241.2827895073583, 2241.8094461970672, 2245.0009745663187, 1939.6702560564738, 2005.9799402006013, 2186.256672731279, 2225.321277010511, 2227.7772222777753, 2221.711177546925, 2232.341459269503, 2251.448395143184, 2254.3124347074136, 2258.503129311549, 7536.321731044232, 7546.32083112517, 7582.545047297124, 7589.81025474362, 7599.620018999028, 4524.751138687371, 4589.036302376505, 4593.563611456906, 4598.448186217642, 4625.514320996281, 2909.61793148255, 2925.3271747497984, 2971.881124755029, 2972.4203780262856, 3018.5321275202377, 883.4875772052194, 887.4808237181256, 900.2123142250531, 907.5915837872983, 920.9815803683956, 10416.687499375032, 10535.367877927349, 10568.0, 10589.311694739894, 10597.258191926569, 4143.150654115899, 4209.284421648327, 4231.11146659205, 4259.74441533509, 4262.488501379792, 9155.084491550924, 9157.77105572366, 9166.26669866409, 9173.81652366949, 9180.403273787182, 4500.527444618333, 4515.254982927846, 4533.7279763213655, 4536.3422303765765, 4539.160255352705, 4027.9597204027586, 4040.6565441937764, 4044.312466880637, 4076.2662720709964, 4094.6928980326593, 3444.810535420526, 3465.54947856777, 3481.8259087045544, 3496.88099808059, 3514.191735900706, 1129.949152288149, 1131.4060118437885, 1133.2294040052843, 1147.3230793831679, 1156.7512984708328, 997.8303688372994, 1250.6185613387822, 1259.6913756129807, 1277.7763891318982, 1278.130871007705, 6174.598651087665, 6841.931580684229, 7126.786196414116, 7151.141862976476, 7159.463040272, 587.4008511318432, 587.9147523609138, 598.3238939997862, 611.2909025530363, 615.4614712126889, 5292.417834038252, 5343.759530821122, 5344.679319240858, 5345.786168553292, 5351.705656188869, 8653.913460865311, 9028.954855225682, 9056.36605437613, 9063.954680226556, 9068.365214435058, 8915.063918288615, 8953.238974687225, 8956.328275379372, 8962.058983806737, 8988.505632190161, 6788.644160493154, 6866.485013623898, 6887.276835932255, 6965.268646792014, 6971.721131154798, 6421.357864213542, 6427.710753016219, 6439.227292724903, 6446.677666116581, 6460.063290822898, 7660.463767536442, 7915.802104947423, 8007.839843202987, 8012.879806803019, 8025.879611805711, 7986.401019923645, 8020.83958320842, 8029.8394032120195, 8046.356291496783, 8127.959360203397, 6570.737170513126, 6602.1747281589805, 6649.800505984828, 6683.43190828785, 6726.428253598303, 7139.714411423485, 7175.46184036189, 7176.282371762782, 7178.820529486596, 7189.2451292615615, 6775.525713200029, 6867.381935625817, 6894.689738961659, 6975.6163411012885, 7005.299470052954, 6861.794146175623, 6918.100646915868, 6922.411595014481, 6994.930050699428, 7004.754833580801, 6574.04676321921, 6613.371729685672, 6627.502937279627, 6629.734810607714, 6671.199456065303, 6114.357992410911, 6125.47933425646, 6134.447899689049, 6138.140660307471, 6143.109249158936, 6200.472959798287, 6266.561340706106, 6270.905936410866, 6274.215723034621, 6281.560290779602], "unit": "ms", "name": "mootools.html", "value": 4260.265602699574}, {"lowerIsBetter": false, "replicates": [320.3704720224758, 327.3796156283842, 327.71979957136654, 340.45357201691303, 341.34802527173105, 5825.242718446596, 5953.821385358436, 6016.849578760533, 6023.969880150598, 6030.035194368902, 5832.066869300902, 5930.318013428447, 5953.166556682068, 5959.255093113361, 5968.432998865112, 4599.999999999992, 4604.746738929358, 4611.169989401907, 4613.769311534426, 4616.33063205835, 4380.277254253045, 4465.0, 4491.505934347219, 4573.199690054244, 4622.399088118556, 4540.432445944257, 4581.106684196583, 4594.839180628679, 4599.540045995391, 4600.608948239404, 4590.586847183736, 4593.63250939924, 4597.494275629678, 4600.930986035212, 4608.907821843544, 4004.2592120457807, 4007.6593489553425, 4014.0767623446773, 4015.578364271739, 4020.632984785187, 5547.583931205176, 5668.263125793662, 5671.120976248688, 5706.80026199081, 5711.771529138871, 5286.920696189561, 5291.947080529184, 5294.894102117974, 5298.867528311785, 5303.204519322094, 6369.745210191587, 6370.267419246799, 6370.840728981766, 6378.362163783631, 6379.075034120026, 4384.276594361926, 4384.364267181241, 4394.51660317368, 4411.823527058914, 4435.844745433894, 6441.194850643669, 6537.771178008747, 6584.308647591982, 6584.8024559263295, 6597.241317248529, 4449.377087207794, 4486.820527178941, 4499.325101234841, 4500.729956202638, 4504.076664283814, 4544.795484203219, 4548.226801443762, 4550.203714349976, 4553.317002449659, 4553.430821147356, 5188.9740551297, 5278.656887302313, 5279.0, 5291.1798671206025, 5299.682019078868, 6350.587211831216, 6386.265579458329, 6389.977603583405, 6401.647909364937, 6404.647744374104, 1573.67739613379, 1573.945456544118, 1574.0948954351293, 1577.1246957938224, 1577.3138684672203, 1060.4591658254346, 1066.7813098314828, 1072.855164552791, 1073.409624706424, 1096.13405409727, 6207.34822843604, 6219.315875253718, 6233.283172435137, 6234.470070044006, 6242.313345531988, 6360.618362898241, 6504.414602685781, 6513.837154071091, 6611.206655201407, 6615.60306381609, 6235.469985051322, 6237.345078766755, 6253.592941588106, 6258.593191442542, 6274.0, 3542.734294927891, 3566.0906468850276, 3586.1751797086818, 3606.9639303606627, 3607.8196090195383, 6651.268360480343, 6828.658567071627, 6836.829079272959, 6862.519623626398, 6865.313468653195, 6773.221079575814, 6789.626570538668, 6792.728290868409, 6793.184817821893, 6794.286599907037, 6844.760433384808, 6847.315268473212, 6847.897281540781, 6849.2465828758795, 6849.897251541231, 6422.6467544285515, 6639.6680165992475, 6641.236257830411, 6645.933540664531, 6652.966735166293, 6557.0, 6558.901616475661, 6577.375149360803, 6579.342065793382, 6592.208934927839, 6833.93166068333, 6839.62382068991, 6850.177978642595, 6851.383375496229, 6851.45188384938, 4346.456692913386, 4431.33529970507, 4459.44256967879, 4463.0, 4475.619572336388, 6564.048213008991, 6566.179227596551, 6570.868582628416, 6571.047198156145, 6574.539782215199, 6391.680415979089, 6393.8721225576155, 6407.871842563216, 6409.359064093554, 6409.647469389229, 6264.154339164337, 6395.328490508429, 6398.776042838478, 6458.031295305742, 6458.063580780855, 6338.683065846597, 6340.49276057905, 6344.365563443619, 6347.079673447417, 6348.333424990309, 6485.156929599122, 6487.870242595216, 6488.967555162194, 6496.935030649633, 6503.577267477599, 6102.420270074336, 6188.504919606331, 6191.318954914866, 6307.590006649553, 6331.176946996861], "unit": "ms", "name": "prototype.html", "value": 4804.382866449413}, {"lowerIsBetter": false, "replicates": [826.0871736730912, 833.9041010283819, 836.899572051354, 849.9957500212498, 865.8701194820778, 13251.072424930258, 13651.112677675945, 13671.248081355521, 13699.794503082461, 13739.24434156121, 12971.805422918664, 13102.672433189175, 14025.719485610307, 14327.140371577712, 14400.927995360009, 672.156443663202, 815.1644564321558, 824.5794644731201, 825.1707034430419, 825.7687847402707, 678.7386856060427, 810.7689308547066, 820.4502983001373, 820.5240960243045, 822.0, 831.7047448155909, 846.1876598465481, 846.7036537211957, 847.2840449819893, 847.8050048488851, 681.8397676546008, 685.4242436353462, 833.8832563441123, 839.6431516605448, 842.6039761312173, 629.1663545801831, 629.5498718416309, 630.0171732097942, 630.7508534129039, 631.1542533005749, 13573.1856088635, 13665.453381864714, 13751.037427380088, 13755.0, 13756.518521851787, 11399.715007124805, 11698.473568689344, 11733.706657333549, 11918.165728399015, 12114.333711645855, 10203.602388268446, 10372.844407333896, 10386.688399348031, 10390.376577405379, 10501.159907207482, 3667.7615954962844, 3717.033571271462, 3724.0, 3743.943840842362, 3837.175007373428, 49839.00321993576, 50431.74784126093, 51044.74477627625, 51112.233316500286, 51157.744211279074, 10106.39361638304, 10256.07695307426, 10315.948420258, 10372.273940824221, 10423.42671153094, 17125.57186070358, 17169.484915452307, 17177.484675459757, 17187.0, 17233.22450489731, 7372.078490188726, 7404.814879628052, 7517.097948246245, 7555.73554925575, 7704.0, 4206.8527601534415, 4251.489821221473, 4252.957470425318, 4260.2970509866145, 4306.095719898856, 3098.411301852641, 3131.7181453669277, 3135.404273188087, 3138.2468207630004, 3150.212446888278, 503.2024241577141, 511.9923201151986, 512.1677274429053, 555.8777069044804, 556.8858384031264, 586.3608666553478, 625.9455931650366, 630.2783313106468, 649.2598437780933, 649.6978904809287, 7644.464887457936, 7674.808129796688, 7737.767866964, 7787.961060194662, 7879.921200787919, 522.1202274167963, 536.3644081763123, 577.8930897783923, 658.5258613798057, 659.3867703036127, 3860.1507668312925, 3947.703922205846, 4177.143685544456, 4185.393117997874, 4337.05511177749, 57364.0, 58228.0, 58316.12525812116, 58534.0, 58627.41372586305, 51661.7416912913, 52145.7392713034, 53197.0, 54189.729051354494, 54918.72540637351, 45632.3155152673, 46442.0, 46684.5331546687, 47031.52968470271, 47402.52597473981, 8301.252887240178, 8351.916480835114, 8488.448250863674, 8501.234888860032, 8530.573471326534, 36157.0, 36270.8186459066, 36972.44541331936, 37199.81400092982, 37492.81253593715, 45679.314810278534, 46876.29685554651, 47289.74392304709, 47718.52281477141, 47902.28146577734, 10440.791184176425, 10802.297850639998, 10873.782524349626, 11059.50232239535, 11329.546818127183, 12002.0, 12134.089943254115, 12246.510139594673, 12285.262884226975, 12316.199447035913, 10813.24307298513, 11026.834597480884, 11194.272372295774, 11349.602763903224, 11366.829497557708, 11050.742697637581, 11379.886201137882, 11683.707907302385, 11793.233439826385, 11912.761744764883, 11374.374409407563, 11511.30932144074, 11517.596884109016, 11658.067354611441, 11897.003258631223, 9367.018433088622, 9980.650677226553, 10004.549795259085, 10187.0, 10366.740831479274, 8778.561071946506, 8845.248153906732, 8848.247898928406, 9147.679831205876, 10012.349197302152], "unit": "ms", "name": "yui.html", "value": 6285.952902418897}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 109.0, 110.0, 111.0, 99.0, 109.0, 109.0, 100.0], "unit": "ms", "name": "ai-astar", "value": 107.1}, {"lowerIsBetter": true, "replicates": [144.0, 125.0, 157.0, 161.0, 125.0, 152.0, 149.0, 126.0, 173.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 160.0, 162.0, 162.0, 160.0, 159.0, 161.0, 162.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 79.0, 93.0, 71.0, 74.0, 71.0, 107.0, 89.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.9}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 147.0, 92.0, 92.0, 92.0, 145.0, 92.0, 92.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 107.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 145.0, 148.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 58.0, 57.0, 58.0, 57.0, 57.0, 61.0, 56.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [71.0, 69.0, 70.0, 70.0, 70.0, 70.0, 70.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.0}, {"lowerIsBetter": true, "replicates": [92.0, 82.0, 84.0, 85.0, 85.0, 84.0, 84.0, 85.0, 94.0, 94.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 86.9}, {"lowerIsBetter": true, "replicates": [123.0, 112.0, 118.0, 112.0, 126.0, 124.0, 123.0, 116.0, 119.0, 129.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.2}, {"lowerIsBetter": true, "replicates": [141.0, 169.0, 154.0, 150.0, 150.0, 151.0, 161.0, 150.0, 151.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.1}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 69.0, 67.0, 66.0, 66.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "Richards", "value": 214.28313999999997}, {"lowerIsBetter": false, "replicates": [194.43904335990666], "unit": "score", "name": "DeltaBlue", "value": 340.0448739999999}, {"lowerIsBetter": false, "replicates": [284.1716396703609], "unit": "score", "name": "Encrypt", "value": 936.6909390000004}, {"lowerIsBetter": false, "replicates": [5133.333333333333], "unit": "score", "name": "Decrypt", "value": 51.85344155844158}, {"lowerIsBetter": false, "replicates": [1123.5955056179776], "unit": "score", "name": "RayTrace", "value": 658.5902099999998}, {"lowerIsBetter": false, "replicates": [777.6049766718507], "unit": "score", "name": "Earley", "value": 857.0714179999998}, {"lowerIsBetter": false, "replicates": [11858.823529411764], "unit": "score", "name": "Boyer", "value": 56.19975694444442}, {"lowerIsBetter": false, "replicates": [25525.0], "unit": "score", "name": "RegExp", "value": 35.689911851126354}, {"lowerIsBetter": false, "replicates": [654.296875], "unit": "score", "name": "Splay", "value": 124.54743880597022}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7"}]} 05:49:33 INFO - RETURN: dromaeo_css: 6966.98 05:49:33 INFO - RETURN: kraken: 1503.00 05:49:33 INFO - RETURN: v8_7: 20897.08 05:49:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,140,35]]", "result": "20897.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,140,35]]", "result": "1503.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,140,35]]", "result": "6966.98"}}} 05:49:33 INFO - Return code: 0 05:49:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:33 INFO - # TBPL SUCCESS # 05:49:33 INFO - Running post-action listener: _resource_record_post_action 05:49:33 INFO - Running post-run listener: _resource_record_post_run 05:49:34 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 12288; Write bytes: 552669184; Read time: 272; Write time: 602824 05:49:34 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 4096; Write bytes: 244654080; Read time: 212; Write time: 403172 05:49:34 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 8192; Write bytes: 303366144; Read time: 60; Write time: 199324 05:49:34 INFO - Running post-run listener: _upload_blobber_files 05:49:34 INFO - Blob upload gear active. 05:49:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:49:34 INFO - Copying logs to upload dir... 05:49:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.797701 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2015-11-26 05:49:34.281623) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:49:34.284729) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009951 build_url: 'https://queue.taskcluster.net/v1/task/A52sFB4pTompey9VvBsLFw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.02 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 05:49:34.310044) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:49:34.310362) ========= 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=b47e8c1238be48f5acbf8f2f000001bb-1448544334.785570-1498986703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 05:49:34.360062) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 05:49:34.360374) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 05:49:34.360661) ========= ========= Total master_lag: 0.40 =========