builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1448536113.35 results: success (0) buildid: 20151126024312 builduid: ed3e497f35784950bd47d25f08b16c3c revision: b499eb4027bdd116e7fd33cc61785d9d7a2860ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.347569) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.348034) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.348347) ========= bash -c pwd in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006389 basedir: '/builds/slave/test' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.380208) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.380545) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.412832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.413214) ========= rm -rf properties in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004546 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.473456) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.473713) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.474058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.474339) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-26 03:08:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 43.6M=0s 2015-11-26 03:08:33 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108251 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.607500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.607836) ========= rm -rf scripts in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020912 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.663169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:33.663603) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b499eb4027bdd116e7fd33cc61785d9d7a2860ec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b499eb4027bdd116e7fd33cc61785d9d7a2860ec --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-26 03:08:34,002 truncating revision to first 12 chars 2015-11-26 03:08:34,003 Setting DEBUG logging. 2015-11-26 03:08:34,003 attempt 1/10 2015-11-26 03:08:34,003 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b499eb4027bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-26 03:08:34,290 unpacking tar archive at: fx-team-b499eb4027bd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.543846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:34.223197) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:34.223537) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:34.234560) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:34.234818) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-26 03:08:34.235717) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-11-26 03:08:34.236032) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (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', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:08:34 INFO - MultiFileLogger online at 20151126 03:08:34 in /builds/slave/test 03:08:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:08:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:08:34 INFO - {'append_to_log': False, 03:08:34 INFO - 'base_work_dir': '/builds/slave/test', 03:08:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:08:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:08:34 INFO - 'branch': 'Fx-Team-Non-PGO', 03:08:34 INFO - 'buildbot_json_path': 'buildprops.json', 03:08:34 INFO - 'config_files': ('talos/linux_config.py',), 03:08:34 INFO - 'default_actions': ('clobber', 03:08:34 INFO - 'read-buildbot-config', 03:08:34 INFO - 'download-and-extract', 03:08:34 INFO - 'populate-webroot', 03:08:34 INFO - 'create-virtualenv', 03:08:34 INFO - 'install', 03:08:34 INFO - 'run-tests'), 03:08:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:08:34 INFO - 'download_minidump_stackwalk': True, 03:08:34 INFO - 'download_symbols': 'ondemand', 03:08:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:08:34 INFO - 'tooltool.py': '/tools/tooltool.py', 03:08:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:08:34 INFO - '/tools/misc-python/virtualenv.py')}, 03:08:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:08:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:08:34 INFO - 'installer_path': 'installer.exe', 03:08:34 INFO - 'log_level': 'info', 03:08:34 INFO - 'log_name': 'talos', 03:08:34 INFO - 'log_to_console': True, 03:08:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:08:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:08:34 INFO - 'opt_config_files': (), 03:08:34 INFO - 'pip_index': False, 03:08:34 INFO - 'sps_profile': False, 03:08:34 INFO - 'sps_profile_interval': 0, 03:08:34 INFO - 'suite': 'dromaeojs', 03:08:34 INFO - 'system_bits': '64', 03:08:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:08:34 INFO - 'title': 'talos-linux64-ix-049', 03:08:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:08:34 INFO - 'use_talos_json': True, 03:08:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:08:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:08:34 INFO - 'work_dir': 'build'} 03:08:34 INFO - ##### 03:08:34 INFO - ##### Running clobber step. 03:08:34 INFO - ##### 03:08:34 INFO - Running pre-action listener: _resource_record_pre_action 03:08:34 INFO - Running main action method: clobber 03:08:34 INFO - rmtree: /builds/slave/test/build 03:08:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:08:35 INFO - Running post-action listener: _resource_record_post_action 03:08:35 INFO - ##### 03:08:35 INFO - ##### Running read-buildbot-config step. 03:08:35 INFO - ##### 03:08:35 INFO - Running pre-action listener: _resource_record_pre_action 03:08:35 INFO - Running main action method: read_buildbot_config 03:08:35 INFO - Using buildbot properties: 03:08:35 INFO - { 03:08:35 INFO - "properties": { 03:08:35 INFO - "buildnumber": 932, 03:08:35 INFO - "product": "firefox", 03:08:35 INFO - "script_repo_revision": "production", 03:08:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:08:35 INFO - "repository": "", 03:08:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 03:08:35 INFO - "buildid": "20151126024312", 03:08:35 INFO - "slavename": "talos-linux64-ix-049", 03:08:35 INFO - "pgo_build": "False", 03:08:35 INFO - "basedir": "/builds/slave/test", 03:08:35 INFO - "project": "", 03:08:35 INFO - "platform": "ubuntu64_hw", 03:08:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:08:35 INFO - "slavebuilddir": "test", 03:08:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:08:35 INFO - "branch": "fx-team", 03:08:35 INFO - "repo_path": "integration/fx-team", 03:08:35 INFO - "stage_platform": "linux64", 03:08:35 INFO - "builduid": "ed3e497f35784950bd47d25f08b16c3c", 03:08:35 INFO - "revision": "b499eb4027bdd116e7fd33cc61785d9d7a2860ec" 03:08:35 INFO - }, 03:08:35 INFO - "sourcestamp": { 03:08:35 INFO - "repository": "", 03:08:35 INFO - "hasPatch": false, 03:08:35 INFO - "project": "", 03:08:35 INFO - "branch": "fx-team-linux64-talos", 03:08:35 INFO - "changes": [ 03:08:35 INFO - { 03:08:35 INFO - "category": null, 03:08:35 INFO - "files": [ 03:08:35 INFO - { 03:08:35 INFO - "url": null, 03:08:35 INFO - "name": "https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 03:08:35 INFO - } 03:08:35 INFO - ], 03:08:35 INFO - "repository": "", 03:08:35 INFO - "rev": "b499eb4027bdd116e7fd33cc61785d9d7a2860ec", 03:08:35 INFO - "who": "cbook@mozilla.com", 03:08:35 INFO - "when": 1448536109, 03:08:35 INFO - "number": 6710013, 03:08:35 INFO - "comments": "Bug 1214214 - Update style of sharing notifications bar, and make it persistent. r=mikedeboer", 03:08:35 INFO - "project": "", 03:08:35 INFO - "at": "Thu 26 Nov 2015 03:08:29", 03:08:35 INFO - "branch": "fx-team-linux64-talos", 03:08:35 INFO - "revlink": "", 03:08:35 INFO - "properties": [ 03:08:35 INFO - [ 03:08:35 INFO - "buildid", 03:08:35 INFO - "20151126024312", 03:08:35 INFO - "Change" 03:08:35 INFO - ], 03:08:35 INFO - [ 03:08:35 INFO - "builduid", 03:08:35 INFO - "ed3e497f35784950bd47d25f08b16c3c", 03:08:35 INFO - "Change" 03:08:35 INFO - ], 03:08:35 INFO - [ 03:08:35 INFO - "pgo_build", 03:08:35 INFO - "False", 03:08:35 INFO - "Change" 03:08:35 INFO - ] 03:08:35 INFO - ], 03:08:35 INFO - "revision": "b499eb4027bdd116e7fd33cc61785d9d7a2860ec" 03:08:35 INFO - } 03:08:35 INFO - ], 03:08:35 INFO - "revision": "b499eb4027bdd116e7fd33cc61785d9d7a2860ec" 03:08:35 INFO - } 03:08:35 INFO - } 03:08:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 03:08:35 INFO - Running post-action listener: _resource_record_post_action 03:08:35 INFO - ##### 03:08:35 INFO - ##### Running download-and-extract step. 03:08:35 INFO - ##### 03:08:35 INFO - Running pre-action listener: _resource_record_pre_action 03:08:35 INFO - Running main action method: download_and_extract 03:08:35 INFO - mkdir: /builds/slave/test/build/tests 03:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:35 INFO - https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/test_packages.json 03:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/test_packages.json 03:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 03:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 03:08:37 INFO - Downloaded 1302 bytes. 03:08:37 INFO - Reading from file /builds/slave/test/build/test_packages.json 03:08:37 INFO - Using the following test package requirements: 03:08:37 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:08:37 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:08:37 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'jsshell-linux-x86_64.zip'], 03:08:37 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:08:37 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:08:37 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:08:37 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:08:37 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:08:37 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 03:08:37 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 03:08:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:08:37 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:37 INFO - https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:08:39 INFO - Downloaded 21510200 bytes. 03:08:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:08:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:08:40 INFO - Return code: 0 03:08:40 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 03:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:40 INFO - https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:08:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:08:44 INFO - Downloaded 21510200 bytes. 03:08:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:08:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 03:08:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:08:45 INFO - Return code: 0 03:08:45 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')]} 03:08:45 INFO - https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:08:46 INFO - Downloaded 11131717 bytes. 03:08:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 03:08:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 03:08:46 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:08:47 INFO - Return code: 0 03:08: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')]} 03:08:47 INFO - https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:08:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:08:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:08:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:08:48 INFO - Downloaded 56849877 bytes. 03:08:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:08:48 INFO - mkdir: /builds/slave/test/properties 03:08:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:08:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:08:48 INFO - Contents: 03:08:48 INFO - build_url:https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 03:08:48 INFO - Running post-action listener: _resource_record_post_action 03:08:48 INFO - Running post-action listener: set_extra_try_arguments 03:08:48 INFO - ##### 03:08:48 INFO - ##### Running populate-webroot step. 03:08:48 INFO - ##### 03:08:48 INFO - Running pre-action listener: _resource_record_pre_action 03:08:48 INFO - Running main action method: populate_webroot 03:08:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:08:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:08:48 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 03:08:48 INFO - '../fennec_ids.txt'], 03:08:48 INFO - 'tests': ['tcheck2']}, 03:08:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:08:48 INFO - '--tppagecycles', 03:08:48 INFO - '7'], 03:08:48 INFO - 'tests': ['tsvgm']}}, 03:08:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:08:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:08:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:08:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:08:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:08:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'tests': ['damp', 'tps']}, 03:08:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['damp', 'tps']}, 03:08:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:08:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:08:48 INFO - 'other': {'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'other_l64': {'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'other_nol64': {'tests': ['a11yr', 03:08:48 INFO - 'ts_paint', 03:08:48 INFO - 'tpaint', 03:08:48 INFO - 'sessionrestore', 03:08:48 INFO - 'sessionrestore_no_auto_restore']}, 03:08:48 INFO - 'svgr': {'tests': ['tsvgx', 03:08:48 INFO - 'tsvgr_opacity', 03:08:48 INFO - 'tart', 03:08:48 INFO - 'tscrollx', 03:08:48 INFO - 'cart']}, 03:08:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['tsvgx', 03:08:48 INFO - 'tsvgr_opacity', 03:08:48 INFO - 'tart', 03:08:48 INFO - 'tscrollx', 03:08:48 INFO - 'cart']}, 03:08:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'tests': ['tp5o']}, 03:08:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'talos_options': ['--e10s'], 03:08:48 INFO - 'tests': ['tp5o']}, 03:08:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'talos_options': ['--xperf_path', 03:08:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:48 INFO - 'tests': ['tp5n']}, 03:08:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:08:48 INFO - 'talos_options': ['--e10s', 03:08:48 INFO - '--xperf_path', 03:08:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:08:48 INFO - 'tests': ['tp5n']}}, 03:08:48 INFO - 'talos.zip': {'path': '', 03:08:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:08:48 INFO - Running post-action listener: _resource_record_post_action 03:08:48 INFO - ##### 03:08:48 INFO - ##### Running create-virtualenv step. 03:08:48 INFO - ##### 03:08:48 INFO - Running pre-action listener: _resource_record_pre_action 03:08:48 INFO - Running main action method: create_virtualenv 03:08:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:08:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:08:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:08:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:08:48 INFO - Using real prefix '/usr' 03:08:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:08:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:08:51 INFO - Installing pip.................done. 03:08:51 INFO - Return code: 0 03:08:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 03:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 03:08:51 INFO - Using env: {'DISPLAY': ':0', 03:08:51 INFO - 'HOME': '/home/cltbld', 03:08:51 INFO - 'LANG': 'en_US.UTF-8', 03:08:51 INFO - 'LANGUAGE': 'en_US:en', 03:08:51 INFO - 'LOGNAME': 'cltbld', 03:08:51 INFO - 'MAIL': '/var/mail/cltbld', 03:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:51 INFO - 'MOZ_NO_REMOTE': '1', 03:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:51 INFO - 'NO_EM_RESTART': '1', 03:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:51 INFO - 'PWD': '/builds/slave/test', 03:08:51 INFO - 'SHELL': '/bin/bash', 03:08:51 INFO - 'SHLVL': '1', 03:08:51 INFO - 'TERM': 'linux', 03:08:51 INFO - 'TMOUT': '86400', 03:08:51 INFO - 'USER': 'cltbld', 03:08:51 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:51 INFO - '_': '/tools/buildbot/bin/python'} 03:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:51 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 03:08:51 INFO - Cleaning up... 03:08:51 INFO - Return code: 0 03:08:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 03:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 03:08:51 INFO - Using env: {'DISPLAY': ':0', 03:08:51 INFO - 'HOME': '/home/cltbld', 03:08:51 INFO - 'LANG': 'en_US.UTF-8', 03:08:51 INFO - 'LANGUAGE': 'en_US:en', 03:08:51 INFO - 'LOGNAME': 'cltbld', 03:08:51 INFO - 'MAIL': '/var/mail/cltbld', 03:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:51 INFO - 'MOZ_NO_REMOTE': '1', 03:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:51 INFO - 'NO_EM_RESTART': '1', 03:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:51 INFO - 'PWD': '/builds/slave/test', 03:08:51 INFO - 'SHELL': '/bin/bash', 03:08:51 INFO - 'SHLVL': '1', 03:08:51 INFO - 'TERM': 'linux', 03:08:51 INFO - 'TMOUT': '86400', 03:08:51 INFO - 'USER': 'cltbld', 03:08:51 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:51 INFO - '_': '/tools/buildbot/bin/python'} 03:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:51 INFO - Downloading/unpacking psutil>=0.7.1 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:08:53 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 03:08:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 03:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:08:53 INFO - Installing collected packages: psutil 03:08:53 INFO - Running setup.py install for psutil 03:08:53 INFO - building 'psutil._psutil_linux' extension 03:08:53 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 03:08:53 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 03:08:53 INFO - building 'psutil._psutil_posix' extension 03:08:53 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 03:08:53 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 03:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:08:53 INFO - Successfully installed psutil 03:08:53 INFO - Cleaning up... 03:08:53 INFO - Return code: 0 03:08:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 03:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 03:08:53 INFO - Using env: {'DISPLAY': ':0', 03:08:53 INFO - 'HOME': '/home/cltbld', 03:08:53 INFO - 'LANG': 'en_US.UTF-8', 03:08:53 INFO - 'LANGUAGE': 'en_US:en', 03:08:53 INFO - 'LOGNAME': 'cltbld', 03:08:53 INFO - 'MAIL': '/var/mail/cltbld', 03:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:53 INFO - 'MOZ_NO_REMOTE': '1', 03:08:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:53 INFO - 'NO_EM_RESTART': '1', 03:08:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:53 INFO - 'PWD': '/builds/slave/test', 03:08:53 INFO - 'SHELL': '/bin/bash', 03:08:53 INFO - 'SHLVL': '1', 03:08:53 INFO - 'TERM': 'linux', 03:08:53 INFO - 'TMOUT': '86400', 03:08:53 INFO - 'USER': 'cltbld', 03:08:53 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:53 INFO - '_': '/tools/buildbot/bin/python'} 03:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08: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 03:08:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:08:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 03:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:08:55 INFO - Installing collected packages: mozsystemmonitor 03:08:55 INFO - Running setup.py install for mozsystemmonitor 03:08:56 INFO - Successfully installed mozsystemmonitor 03:08:56 INFO - Cleaning up... 03:08:56 INFO - Return code: 0 03:08:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 03:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 03:08:56 INFO - Using env: {'DISPLAY': ':0', 03:08:56 INFO - 'HOME': '/home/cltbld', 03:08:56 INFO - 'LANG': 'en_US.UTF-8', 03:08:56 INFO - 'LANGUAGE': 'en_US:en', 03:08:56 INFO - 'LOGNAME': 'cltbld', 03:08:56 INFO - 'MAIL': '/var/mail/cltbld', 03:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:56 INFO - 'MOZ_NO_REMOTE': '1', 03:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:56 INFO - 'NO_EM_RESTART': '1', 03:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:56 INFO - 'PWD': '/builds/slave/test', 03:08:56 INFO - 'SHELL': '/bin/bash', 03:08:56 INFO - 'SHLVL': '1', 03:08:56 INFO - 'TERM': 'linux', 03:08:56 INFO - 'TMOUT': '86400', 03:08:56 INFO - 'USER': 'cltbld', 03:08:56 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:56 INFO - '_': '/tools/buildbot/bin/python'} 03:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:56 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 03:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:08:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 03:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:08:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 03:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 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 03:08:58 INFO - Downloading docopt-0.6.1.tar.gz 03:08:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 03:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:08:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:08:58 INFO - Running setup.py install for blobuploader 03:08:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:08:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:08:58 INFO - Running setup.py install for requests 03:08:59 INFO - Running setup.py install for docopt 03:08:59 INFO - Successfully installed blobuploader requests docopt 03:08:59 INFO - Cleaning up... 03:08:59 INFO - Return code: 0 03:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 03:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 03:08:59 INFO - Using env: {'DISPLAY': ':0', 03:08:59 INFO - 'HOME': '/home/cltbld', 03:08:59 INFO - 'LANG': 'en_US.UTF-8', 03:08:59 INFO - 'LANGUAGE': 'en_US:en', 03:08:59 INFO - 'LOGNAME': 'cltbld', 03:08:59 INFO - 'MAIL': '/var/mail/cltbld', 03:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:59 INFO - 'MOZ_NO_REMOTE': '1', 03:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:59 INFO - 'NO_EM_RESTART': '1', 03:08:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:59 INFO - 'PWD': '/builds/slave/test', 03:08:59 INFO - 'SHELL': '/bin/bash', 03:08:59 INFO - 'SHLVL': '1', 03:08:59 INFO - 'TERM': 'linux', 03:08:59 INFO - 'TMOUT': '86400', 03:08:59 INFO - 'USER': 'cltbld', 03:08:59 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:59 INFO - '_': '/tools/buildbot/bin/python'} 03:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:08:59 INFO - Running setup.py (path:/tmp/pip-7qe5Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:08:59 INFO - Running setup.py (path:/tmp/pip-2oOhH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:08:59 INFO - Running setup.py (path:/tmp/pip-bVHKio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:08:59 INFO - Running setup.py (path:/tmp/pip-7q5TIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:08:59 INFO - Running setup.py (path:/tmp/pip-5_zbil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:00 INFO - Running setup.py (path:/tmp/pip-UV9amp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:00 INFO - Running setup.py (path:/tmp/pip-wWPRNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:00 INFO - Running setup.py (path:/tmp/pip-ceV5ZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:00 INFO - Running setup.py (path:/tmp/pip-ij7J6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:00 INFO - Running setup.py (path:/tmp/pip-On8hHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:00 INFO - Running setup.py (path:/tmp/pip-NlM_MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:00 INFO - Running setup.py (path:/tmp/pip-OkeNw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:00 INFO - Running setup.py (path:/tmp/pip-9pSsnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:00 INFO - Running setup.py (path:/tmp/pip-V4nX6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:00 INFO - Running setup.py (path:/tmp/pip-geTWAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:00 INFO - Running setup.py (path:/tmp/pip-PcDttc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:00 INFO - Running setup.py (path:/tmp/pip-oCjOWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:00 INFO - Running setup.py install for manifestparser 03:09:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:00 INFO - Running setup.py install for mozcrash 03:09:01 INFO - Running setup.py install for mozdebug 03:09:01 INFO - Running setup.py install for mozdevice 03:09:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Running setup.py install for mozfile 03:09:01 INFO - Running setup.py install for mozhttpd 03:09:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Running setup.py install for mozinfo 03:09:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Running setup.py install for mozInstall 03:09:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Running setup.py install for mozleak 03:09:01 INFO - Running setup.py install for mozlog 03:09:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:01 INFO - Running setup.py install for moznetwork 03:09:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Running setup.py install for mozprocess 03:09:02 INFO - Running setup.py install for mozprofile 03:09:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Running setup.py install for mozrunner 03:09:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Running setup.py install for mozscreenshot 03:09:02 INFO - Running setup.py install for moztest 03:09:02 INFO - Running setup.py install for mozversion 03:09:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:02 INFO - Cleaning up... 03:09:02 INFO - Return code: 0 03:09:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 03:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 03:09:02 INFO - Using env: {'DISPLAY': ':0', 03:09:02 INFO - 'HOME': '/home/cltbld', 03:09:02 INFO - 'LANG': 'en_US.UTF-8', 03:09:02 INFO - 'LANGUAGE': 'en_US:en', 03:09:02 INFO - 'LOGNAME': 'cltbld', 03:09:02 INFO - 'MAIL': '/var/mail/cltbld', 03:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:02 INFO - 'MOZ_NO_REMOTE': '1', 03:09:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:02 INFO - 'NO_EM_RESTART': '1', 03:09:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:02 INFO - 'PWD': '/builds/slave/test', 03:09:02 INFO - 'SHELL': '/bin/bash', 03:09:02 INFO - 'SHLVL': '1', 03:09:02 INFO - 'TERM': 'linux', 03:09:02 INFO - 'TMOUT': '86400', 03:09:02 INFO - 'USER': 'cltbld', 03:09:02 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:02 INFO - '_': '/tools/buildbot/bin/python'} 03:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:02 INFO - Running setup.py (path:/tmp/pip-wRhrV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:02 INFO - Running setup.py (path:/tmp/pip-64YCbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:03 INFO - Running setup.py (path:/tmp/pip-JrGlIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:03 INFO - Running setup.py (path:/tmp/pip-uCKSe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:03 INFO - Running setup.py (path:/tmp/pip-dOWYg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:03 INFO - Running setup.py (path:/tmp/pip-k1m35N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:03 INFO - Running setup.py (path:/tmp/pip-jID1LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:03 INFO - Running setup.py (path:/tmp/pip-EIfUdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:03 INFO - Running setup.py (path:/tmp/pip-4gm5lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:03 INFO - Running setup.py (path:/tmp/pip-oun1ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:03 INFO - Running setup.py (path:/tmp/pip-latVV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:03 INFO - Running setup.py (path:/tmp/pip-DHgX1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:03 INFO - Running setup.py (path:/tmp/pip-O4VFP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:03 INFO - Running setup.py (path:/tmp/pip-oXX8p4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:03 INFO - Running setup.py (path:/tmp/pip-T_F4iX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:09:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:04 INFO - Running setup.py (path:/tmp/pip-_b4Hoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:04 INFO - Running setup.py (path:/tmp/pip-7Cy23q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:09:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:05 INFO - Downloading blessings-1.5.1.tar.gz 03:09:05 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 03:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:05 INFO - Installing collected packages: blessings 03:09:05 INFO - Running setup.py install for blessings 03:09:06 INFO - Successfully installed blessings 03:09:06 INFO - Cleaning up... 03:09:06 INFO - Return code: 0 03:09:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:06 INFO - Reading from file tmpfile_stdout 03:09:06 INFO - Current package versions: 03:09:06 INFO - argparse == 1.2.1 03:09:06 INFO - blessings == 1.5.1 03:09:06 INFO - blobuploader == 1.2.4 03:09:06 INFO - docopt == 0.6.1 03:09:06 INFO - manifestparser == 1.1 03:09:06 INFO - mozInstall == 1.12 03:09:06 INFO - mozcrash == 0.16 03:09:06 INFO - mozdebug == 0.1 03:09:06 INFO - mozdevice == 0.47 03:09:06 INFO - mozfile == 1.2 03:09:06 INFO - mozhttpd == 0.7 03:09:06 INFO - mozinfo == 0.9 03:09:06 INFO - mozleak == 0.1 03:09:06 INFO - mozlog == 3.1 03:09:06 INFO - moznetwork == 0.27 03:09:06 INFO - mozprocess == 0.22 03:09:06 INFO - mozprofile == 0.27 03:09:06 INFO - mozrunner == 6.11 03:09:06 INFO - mozscreenshot == 0.1 03:09:06 INFO - mozsystemmonitor == 0.0 03:09:06 INFO - moztest == 0.7 03:09:06 INFO - mozversion == 1.4 03:09:06 INFO - psutil == 3.1.1 03:09:06 INFO - requests == 1.2.3 03:09:06 INFO - wsgiref == 0.1.2 03:09:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 03:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/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 03:09:06 INFO - Using env: {'DISPLAY': ':0', 03:09:06 INFO - 'HOME': '/home/cltbld', 03:09:06 INFO - 'LANG': 'en_US.UTF-8', 03:09:06 INFO - 'LANGUAGE': 'en_US:en', 03:09:06 INFO - 'LOGNAME': 'cltbld', 03:09:06 INFO - 'MAIL': '/var/mail/cltbld', 03:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:06 INFO - 'MOZ_NO_REMOTE': '1', 03:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:06 INFO - 'NO_EM_RESTART': '1', 03:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:06 INFO - 'PWD': '/builds/slave/test', 03:09:06 INFO - 'SHELL': '/bin/bash', 03:09:06 INFO - 'SHLVL': '1', 03:09:06 INFO - 'TERM': 'linux', 03:09:06 INFO - 'TMOUT': '86400', 03:09:06 INFO - 'USER': 'cltbld', 03:09:06 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:06 INFO - '_': '/tools/buildbot/bin/python'} 03:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:09:06 INFO - Cleaning up... 03:09:06 INFO - Return code: 0 03:09:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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 0x23ad030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x250f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25de210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 03:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/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/build 03:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/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 03:09:06 INFO - Using env: {'DISPLAY': ':0', 03:09:06 INFO - 'HOME': '/home/cltbld', 03:09:06 INFO - 'LANG': 'en_US.UTF-8', 03:09:06 INFO - 'LANGUAGE': 'en_US:en', 03:09:06 INFO - 'LOGNAME': 'cltbld', 03:09:06 INFO - 'MAIL': '/var/mail/cltbld', 03:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:06 INFO - 'MOZ_NO_REMOTE': '1', 03:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:06 INFO - 'NO_EM_RESTART': '1', 03:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:06 INFO - 'PWD': '/builds/slave/test', 03:09:06 INFO - 'SHELL': '/bin/bash', 03:09:06 INFO - 'SHLVL': '1', 03:09:06 INFO - 'TERM': 'linux', 03:09:06 INFO - 'TMOUT': '86400', 03:09:06 INFO - 'USER': 'cltbld', 03:09:06 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:06 INFO - '_': '/tools/buildbot/bin/python'} 03:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:06 INFO - Downloading/unpacking jsonschema 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:08 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 03:09:08 INFO - Downloading/unpacking functools32 (from jsonschema) 03:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:08 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 03:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:08 INFO - warning: no files found matching '*.txt' 03:09:08 INFO - no previously-included directories found matching 'build' 03:09:08 INFO - no previously-included directories found matching 'dist' 03:09:08 INFO - no previously-included directories found matching '.git*' 03:09:08 INFO - Installing collected packages: jsonschema, functools32 03:09:08 INFO - Running setup.py install for functools32 03:09:08 INFO - warning: no files found matching '*.txt' 03:09:08 INFO - no previously-included directories found matching 'build' 03:09:08 INFO - no previously-included directories found matching 'dist' 03:09:08 INFO - no previously-included directories found matching '.git*' 03:09:08 INFO - Successfully installed jsonschema functools32 03:09:08 INFO - Cleaning up... 03:09:08 INFO - Return code: 0 03:09:08 INFO - Running post-action listener: _resource_record_post_action 03:09:08 INFO - Running post-action listener: _start_resource_monitoring 03:09:09 INFO - Starting resource monitoring. 03:09:09 INFO - ##### 03:09:09 INFO - ##### Running install step. 03:09:09 INFO - ##### 03:09:09 INFO - Running pre-action listener: _resource_record_pre_action 03:09:09 INFO - Running main action method: install 03:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:09 INFO - Reading from file tmpfile_stdout 03:09:09 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:09 INFO - Reading from file tmpfile_stdout 03:09:09 INFO - Output received: 03:09:09 INFO - Usage: mozinstall [options] installer 03:09:09 INFO - Options: 03:09:09 INFO - -h, --help show this help message and exit 03:09:09 INFO - -d DEST, --destination=DEST 03:09:09 INFO - Directory to install application into. [default: 03:09:09 INFO - "/builds/slave/test"] 03:09:09 INFO - --app=APP Application being installed. [default: firefox] 03:09:09 INFO - mkdir: /builds/slave/test/build/application 03:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:09:23 INFO - Reading from file tmpfile_stdout 03:09:23 INFO - Output received: 03:09:23 INFO - /builds/slave/test/build/application/firefox/firefox 03:09:23 INFO - Running post-action listener: _resource_record_post_action 03:09:23 INFO - ##### 03:09:23 INFO - ##### Running run-tests step. 03:09:23 INFO - ##### 03:09:23 INFO - Running pre-action listener: _resource_record_pre_action 03:09:23 INFO - Running main action method: run_tests 03:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:09:23 INFO - Python 2.7.3 03:09:23 INFO - Return code: 0 03:09:23 INFO - grabbing minidump binary from tooltool 03:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/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 0x2670220>, '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 0x25dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25dc740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:09:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:09:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:09:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:09:23 INFO - Return code: 0 03:09:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:09:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:09:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:09:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:09:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] in /builds/slave/test/build 03:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost 03:09:23 INFO - Using env: {'DISPLAY': ':0', 03:09:23 INFO - 'HOME': '/home/cltbld', 03:09:23 INFO - 'LANG': 'en_US.UTF-8', 03:09:23 INFO - 'LANGUAGE': 'en_US:en', 03:09:23 INFO - 'LOGNAME': 'cltbld', 03:09:23 INFO - 'MAIL': '/var/mail/cltbld', 03:09:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:09:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:23 INFO - 'MOZ_NO_REMOTE': '1', 03:09:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:23 INFO - 'NO_EM_RESTART': '1', 03:09:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:23 INFO - 'PWD': '/builds/slave/test', 03:09:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:09:23 INFO - 'SHELL': '/bin/bash', 03:09:23 INFO - 'SHLVL': '1', 03:09:23 INFO - 'TERM': 'linux', 03:09:23 INFO - 'TMOUT': '86400', 03:09:23 INFO - 'USER': 'cltbld', 03:09:23 INFO - 'XDG_SESSION_COOKIE': '0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152', 03:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:23 INFO - '_': '/tools/buildbot/bin/python'} 03:09:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] with output_timeout 3600 03:09:23 INFO - mozversion INFO | application_buildid: 20151126024312 03:09:23 INFO - mozversion INFO | application_changeset: b499eb4027bdd116e7fd33cc61785d9d7a2860ec 03:09:23 INFO - mozversion INFO | application_display_name: Nightly 03:09:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:09:23 INFO - mozversion INFO | application_name: Firefox 03:09:23 INFO - mozversion INFO | application_remotingname: firefox 03:09:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 03:09:23 INFO - mozversion INFO | application_vendor: Mozilla 03:09:23 INFO - mozversion INFO | application_version: 45.0a1 03:09:23 INFO - mozversion INFO | platform_buildid: 20151126024312 03:09:23 INFO - mozversion INFO | platform_changeset: b499eb4027bdd116e7fd33cc61785d9d7a2860ec 03:09:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 03:09:23 INFO - mozversion INFO | platform_version: 45.0a1 03:09:23 INFO - 2015-11-26 03:09:23,898 DEBUG : using testdate: 1448536163 03:09:23 INFO - 2015-11-26 03:09:23,898 DEBUG : actual date: 1448536163 03:09:23 INFO - 2015-11-26 03:09:23,913 INFO : starting webserver on 'localhost:46622' 03:09:23 INFO - 2015-11-26 03:09:23,914 INFO : Starting test suite talos-linux64-ix-049 03:09:23 INFO - 2015-11-26 03:09:23,914 INFO : Starting test dromaeo_css 03:09:23 INFO - 2015-11-26 03:09:23,914 DEBUG : operating with platform_type : linux_ 03:09:23 INFO - 2015-11-26 03:09:23,915 INFO : Initialising browser for dromaeo_css test... 03:09:23 INFO - 2015-11-26 03:09:23,931 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiRejZz/profile http://localhost:46622/getInfo.html 03:09:27 INFO - 2015-11-26 03:09:27,865 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:09:27 INFO - 2015-11-26 03:09:27,865 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:09:27 INFO - 2015-11-26 03:09:27,865 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:09:27 INFO - 2015-11-26 03:09:27,865 DEBUG : BROWSER_OUTPUT: __metrics 03:09:27 INFO - 2015-11-26 03:09:27,966 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] 03:09:29 INFO - 2015-11-26 03:09:29,150 INFO : Browser initialized. 03:09:29 INFO - 2015-11-26 03:09:29,151 INFO : Running cycle 1/1 for dromaeo_css test... 03:09:29 INFO - 2015-11-26 03:09:29,151 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiRejZz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:09:40 INFO - 2015-11-26 03:09:40,931 DEBUG : BROWSER_OUTPUT: RSS: Main: 139149312 03:09:40 INFO - 2015-11-26 03:09:40,931 DEBUG : BROWSER_OUTPUT: 03:10:32 INFO - 2015-11-26 03:10:32,157 DEBUG : BROWSER_OUTPUT: 1448536232155 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] 03:12:44 INFO - 2015-11-26 03:12:44,221 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46622/tests/dromaeo/cssquery-ext.html) 03:12:45 INFO - 2015-11-26 03:12:45,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 244076544 03:12:45 INFO - 2015-11-26 03:12:45,351 DEBUG : BROWSER_OUTPUT: 03:15:47 INFO - 2015-11-26 03:15:47,806 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-ext.html (next: http://localhost:46622/tests/dromaeo/cssquery-jquery.html) 03:15:48 INFO - 2015-11-26 03:15:48,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 214163456 03:15:48 INFO - 2015-11-26 03:15:48,372 DEBUG : BROWSER_OUTPUT: 03:18:50 INFO - 2015-11-26 03:18:50,415 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46622/tests/dromaeo/cssquery-mootools.html) 03:18:50 INFO - 2015-11-26 03:18:50,988 DEBUG : BROWSER_OUTPUT: RSS: Main: 217899008 03:18:50 INFO - 2015-11-26 03:18:50,988 DEBUG : BROWSER_OUTPUT: 03:21:52 INFO - 2015-11-26 03:21:52,901 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46622/tests/dromaeo/cssquery-prototype.html) 03:21:53 INFO - 2015-11-26 03:21:53,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 197996544 03:21:53 INFO - 2015-11-26 03:21:53,487 DEBUG : BROWSER_OUTPUT: 03:24:56 INFO - 2015-11-26 03:24:56,315 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46622/tests/dromaeo/cssquery-yui.html) 03:24:56 INFO - 2015-11-26 03:24:56,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 204496896 03:24:56 INFO - 2015-11-26 03:24:56,916 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,439 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/dromaeo/cssquery-yui.html (next: http://localhost:46622/tests/dromaeo/cssquery-dojo.html) 03:27:59 INFO - 2015-11-26 03:27:59,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 249221120 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:27:59 INFO - 2015-11-26 03:27:59,698 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2631.644727961725;2704.3509557706134;2707.3773032202594;2728.140635699753;2743.8628068596563;4587;4684;4691.507391723867;4699.1776439123105;4707.270373092168;4591.816327346911;4653.162430762462;4695.014047050115;4709.081729062826;4722.795687099793;4297.871063868089;4309.030468144674;4317.935230971522;4335.241332766769;4340.392345071693;4152.97923510382;4439.045605194882;4442.733435993835;4487.596116349528;4491.528389519102;4230.788460576959;4321.913561728764;4329.437173167499;4332.415123958257;4351.673624478177;4309.2458819706735;4319.33050377192;4325.416068830699;4326.6538676905775;4369.169857727022;4300.268954277749;4313.525512193688;4314.892127696801;4337.0458499130455;4341.761203133827;4495.280755079204;4550.954490455086;4568.063546972862;4601.493835678073;4646.256598944186;5009.373828271466;5038.571721403685;5038.79844806211;5044.899102017976;5087.262346959707;5070;5070.6957582545165;5076.390833100015;5077.796888124471;5129.487051294878;4478.708883922534;4571.017231295221;4592.563706447849;4593.724376537419;4595.931061034087;5141.074606570854;5144.279800827887;5146.176611742103;5155.74221288934;5191.22131680251;4946.727929963815;4953.23224900141;4953.306537084811;4956.107900577858;4997.700137991732;4647.744374059459;4767.976160119178;4792.137415265216;4799.136155491975;5026.974865125651;4858.854234372974;4884.267359896044;4888.26675998596;4894.143524883131;4916.065947470041;4938.135826230396;4938.247041677668;4951.678140920829;4954.677945933575;4982.8754281143265;3972.3842804365368;4059.4925634295714;4124.092699606082;4152.5432202457705;4204.726692764961;3593.6226696197045;3643.9635603644156;3645.7630254033934;3661.7436779425716;3678.8528458861883;4273.380359847804;4300.548442413501;4305.117450922553;4308.159908817813;4331.891702707458;4829.5653391194965;4938.456769755324;4999.775010124553;5004.199328107486;5060.645754797129;4225.070484493406;4270.786460677016;4279.358096285582;4290.914181716348;4319.157764236007;4458.5318541553315;4459.241928872099;4463.977680111579;4465.531119232433;4497.617702495259;4557.772111394351;4659.673822832369;4776.689515181502;4781.880452988634;4783.976080119577;4868.07506573764;4911.4106307243355;4978.8257410990445;4993.825216117419;5039.899202016012;4684.461286952044;4735.029318989667;4739.312799643963;4744.92882606768;4747.905041899212;4751.904961900674;4834.758262086952;4934.235193545006;4945.505449455026;4991.47589503097;4832.371791667201;4954.801807927643;5038.798448062036;5044.1424957756535;5083.72039537829;4912.238603016538;4915.5330243626795;4931.876703082308;4940.975295123501;4983.925241121314;4686.789094490824;4823.782929768239;4919.606431485544;4923.384576927884;4969;4681.110588988081;4691.812327506861;4696.647751418725;4736.455307639666;4768.356271903249;4929.260610908393;4930.285108659296;4932.186189278872;4942.851714448572;4980.203167493184;4610.2623580227;4702.52974702527;4830.420349558076;4839.588634965927;4879.072976134523 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: |1;ext.html;463.1570541614261;474.4093603463688;507.6421123108208;509.666168659528;514.7812179823574;21192.25827096052;21651.133954641817;31649.84175079124;32391;32451.188720281945;33576.49635255473;33941.490877636854;33958.49062264062;33976.660233397655;34018.319633607316;6525.93474065258;7586.279303466162;7732.072151341847;7750.108737495176;7762.223777622249;6549.541532092755;9395.295352848529;9526.666566670134;9602.327837051409;9630.133288004079;9586.233101351874;9658.71023869285;9704.563294651758;9704.902950970505;9711.320207585472;6598.37315455031;6820.3861652451515;9354.532273386303;9391.43651380916;9633.325667203299;9397.107274808883;9538.236941044699;9545.188658963927;9547.236221102296;9549.236061115094;31162.84418577915;31462.842685786425;31542.36915261682;31586.36827263465;31619.683803161905;2465.3590066582638;2642.4582960493053;2868.770498360147;3014.608100946863;3148.2916343822685;24434.02852147578;24743;25103.37241568957;25255.62116568253;25560.360990975187;3246.4643333849885;3351.9497207541663;3371.2920286739813;3387.915302117442;3417.9658203418144;226351.23312052162;226870;227623.99999999834;228073;228360;31588.52617210744;32373.838130809196;39045.219095617926;39253.411198832044;45542.77228613836;43645.781771090944;43851;43885.341719874225;43897.78051109788;43992.78003609961;11286.379249141377;11324.886751132382;11375.374354410587;11401.258918170459;11448;9150.313726470542;9158.86261706075;9253.768655783524;9288.11762882525;9294.209992150607;14263.786043209153;14518.128912265298;14664.633384165481;14740.484083057041;14843;5852.502537284378;5982.850428739229;5987.9101813472835;6009.939900600938;6013.669248191304;720.0315575550876;729.584137041881;883.0418995389946;894.9550277615747;896.3098414221014;22101.778982210293;22310.10759569599;22397.55204895893;22409.21567745121;22528.774712252667;834.7787836223406;883.1433509495779;885.4864178776294;898.6809682562726;904.5974541329045;11662.94168529169;12007.639770806889;12027.398630068637;12043.277403355827;12054;265138;269449;270190;270244;270934;36511.4523282151;36683.26633467316;36723;36822.8158859204;37017.81491092581;997.234894237196;1013.7277716465788;1030.6238223048683;1030.6907927621833;1036.8341065429645;21966.121355146253;22364;22755.431114221614;22777;22851.42871428161;24395.51208975846;24644.507109857343;24650;32882.5067623981;33712.49431258582;322184;327947;330145;330301;332105;24011.51976960486;24119.51760964806;24342.391440214138;24346;24361.878190608935;22054.228102016354;23361;25682.101126460486;30988.22529436782;32707.34585308328;24021.51956960816;24111.879440602686;24148.75851241465;24588;24886.37784055413;25566.744332556435;25912.48175036451;26110.34724131912;26188.08341708031;26251.60622590701;27048.594271086342;27141.86429067842;27349.86325068362;27611.447771044066;27664.723352766214;25238.369040774127;25322.873385632953;25683.35791605225;25868.889587292946;26183.476330473666;18434.354797582022;18522.0738963054;18715.25138994425;18789.530261743566;20741.68887466659 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: |2;jquery.html;320.90854106579627;323.3371588244099;324.24465733235076;324.56022090067955;325.4727341706436;11407;11796.941015294911;13899.861001389983;14161.787573186397;14362.640933976656;12984.156029858077;13304.534341298056;13329.33383327084;13814.1020833646;13967;4478.350639157312;4575.954240457603;4577.862664120082;4581.541845815408;4876.073546026261;3268.2319654881203;3350.329934013207;5057.468965758598;5098.668586541863;5268.1834315681135;5232.8691782705355;5234.162533994561;5236.450172731866;5253.3170687810725;5259.000789849916;5136.460671629481;5234.947650523484;5573.832785016456;5596.3284405871145;5614.29821272341;4982.975085124587;4983.127952608279;4986.975065124688;4994.475580064077;5001.699898006095;11685.357305348203;11703.94148029263;11731.413429328499;11733.178677492579;15241.6189595261;1661.1611136376173;1704.181992643538;1712.2637265975625;1719.4325872462057;1880.6426778911964;8514.531700756455;8897.288216942694;8943.06097859722;10807.78384432307;11705.824412633818;1659.7593348964333;1681.3862940026802;1710.1876608610935;1721.5782133377277;1773.0336966353368;54239.728801356134;56985.1452228221;57018;57553;57631.71184144094;8960.238379737795;9003.189712925869;9360.4383736976;9574.569144388519;9660.806783864284;11936.164468487123;12037.819432708517;12125.151239413333;12153.756924861453;12157.878421215852;6376.458001069961;6506.967465162738;6533.477321814244;6535;6576.8355791105605;6115.6330620162935;6122.989706698345;6140.4473597376455;6161.938380616137;6178.41305076017;3944.861929832442;4013.5585085640555;4018.6584140347813;4034.455348527911;4084.101497670508;1445.3712635003808;1493.9402423903139;1567.882408819343;1637.7052130616603;1637.8853480256269;626.7179769103922;699.8040548646387;712.7576623947882;760.3916866506772;792.2908996448147;13227.404766785517;13511.391987360592;13598.660033499242;13748.381322840496;13922.651933701738;548.964317319373;711.8362776561316;714.1572943926217;714.3106901839645;741.1180694972965;4988.326575912278;7891.48705334163;7895.340373129156;8032.558209298545;8035.397345199017;467758;472518;477008;477130;490587;70232;70540.64729676422;70705.64647176834;70707.6464617684;71380;882.1619461511461;883.0860059838037;888.813349196663;892.1123482135317;908.7955210077812;12681.492740290285;12714.237145771283;12881.677958050748;13045.739085218052;13181.538646147339;30276;30392.656807751573;31362.372752545278;31553.684463155074;31752.841235793672;42153;42372.78813605912;42995.35506967335;44565.55434445614;44921.55078449174;8870.15733505316;8903.643854245758;8996.86504702443;8998.910010899806;9032.14194651507;10292.536835842255;12929.288889111189;15428;15484.612884677974;15620.453284135001;12425.565105221636;12711.745765084832;12758.48966041385;12766.68083297925;13042.087053906136;12404.81392779091;12764.361781910682;13034;13145.342732863128;13343.399547020601;12028;12384.56654017105;12444.688882778002;12523.436445360163;12897;8654.394192406722;8697.304215662858;8707.303415726854;8876.378653494452;8881.911180888108;12018.218815776947;12054.819177712518;12460.688482788002;12567.120301578801;12788.360581970679 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: |3;mootools.html;883.6244596046679;1137.550667486343;1165.5920427850253;1171.1743221029174;1297.3253907967858;5311.415744268127;5695.772169113236;6655.334466553349;6809.148856392951;6821.9658901705425;6805.149356330458;6837.726490940368;6890.414314783249;6903.516753827234;6912.4470042396615;1678.3706110208673;1724.1379310344828;2054.6198953193634;2089.028601700209;2089.3836318286144;1698.5498842806667;2041.1325186795627;2084.7810979847127;2086.62440760663;2088.363049269972;2014.969775453362;2038.3681058871723;2044.6524090904506;2047.7645070816823;2059.042545216474;1966.8918209498472;1998.490384951828;2001.2995451591826;2002.8798272103647;2003.699445083242;2032.095717405755;2065.5662310914727;2067.24545540878;2069.3998740365278;2070.130545171032;6786.185657721056;6851.691673874688;6864.313568643146;6868.690908909111;6878.3465570770695;3918.3730603103368;3947.407888816672;3977.1846771411792;3997.34043882759;4011.959880401217;2705.4318593095472;2735.193118030166;2747.7801775858084;2757.765589924859;2758.8758505867286;857.5455008845322;862.9352798540135;893.423741686615;894.8120894612139;897.7800438892448;9211;9212.815743685089;9228.40015398997;9234.30742694294;9240.445573265626;3839.8080095995088;3886.9611303887164;3904.4143378493454;3912.3349030664854;3976.5824588418377;8033.277005069572;8038.39712021589;8055.275025247756;8055.556944367999;8056.194380562014;4017.7991100445447;4023.6781057515327;4030.052937559671;4033.3344998075577;4034.3545032794614;3654.798986055739;3659.3779057560278;3660.5790334111916;3663.249033948034;3667.339878821838;2703.39173685923;2750.6286651302216;3228.1606782236786;3289.671032896691;3300.207950091961;1005.0954141272913;1041.5937784264142;1050.6795427394718;1057.8148823955778;1061.3101484035285;761.3566536276837;885.8582626779685;907.4419232172298;908.1191244492832;909.9545022748836;6317.557770955989;6390.616563006234;6392.4246817786625;6396.0405939109505;6398.200224971879;890.7461373508519;901.0944001278673;906.6871929184421;909.5315912305168;917.417439925652;4529.433820772403;4593.540645935446;4596.954030459653;4599.862004139881;4603.240465323184;7932.722354717557;8097.39269554774;8117.634706438106;8162.3878209134555;8479.19447652472;7992.760217193493;8053.959730201312;8068.919310806817;8101.716439924692;8108.878366824384;6147.538934579924;6159.23009623797;6203.658798766111;6251.9687401562705;6267.749290028439;6243.469305109116;6299.590526615755;6307.148534947907;6354.301026887131;6357.268914074941;7249.855002900018;7389.667464964197;7449.292317229854;7502.662380192795;7547.698092076255;7553.0936287643635;7621.047369078865;7642.847143057219;7681.193474685301;7749.8837517438415;5825.621334613236;5847;5863.120531920246;5870.090136028923;5880.8235752927485;6194.535409844189;6219.564630476004;6247.125402443752;6422.068800024063;6426.100345951477;5757.740901659352;5893.410658934073;5946.672932988728;6055.364186760503;6066.332703402534;5754.68349240779;5775.422457754191;5811.389804070512;5888.646681199141;5919.5856290061;5897.3807750187325;5906.350301466749;5913.290405151237;5923.437273459014;5931.9110213345975;5461.972690136683;5493.642913210629;5537.8061767837935;5569.10894256917;5572.526335261548;5578.163275508544;5582.302212223472;5596.328440586993;5597.44025597437;5623.2408624835125 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: |4;prototype.html;288.97399234068934;292.7804146889833;294.62435394871534;295.27215414004473;296.991090267292;5437.667771396009;5446.509814116734;5464.26232458618;5507.201455788908;5533.059379905416;5388.407275199725;5389.973050134754;5394.352677678684;5399.811006614769;5403.486668766471;4172.102997855461;4198.391233271173;4200.075983283673;4200.390943313218;4200.474940632414;3942.960570394288;4059.106996460774;4069.470968774055;4147.875563733093;4148.191102734968;4095.75425474471;4107.17856428714;4107.404426358176;4116.34138537834;4120.855770048049;3875.3218186817194;4008.7372477669733;4156.750594964312;4158.480189976253;4172.749635021908;3507.982460087683;3620.3664358737115;3643.8178091095606;3645.106948797535;3654.908627284339;5145.33110695611;5173.896522069575;5204.9219261711105;5214.765335559871;5222.63441559091;4782.019685964369;4795.8729698521165;4796.496367881392;4796.7841447134615;4798.872265017753;5450.236966824647;5681.204631351614;5686.943130568683;5687.630304030266;5692.345380281279;3879.612038796126;3884.1454879926373;3885.805709714503;3887.300285948528;3888.5333759948985;5919.704014799243;5962.612430192024;5983.072623743326;6017.849553761147;6020.909686354708;3980.20395920817;3987.4847557928383;3991.780452075164;3991.840326386912;3993.4209539617173;3739.476473293741;3771.321162190832;4040.676745860324;4043.838246470108;4050.736702114353;4508.616767574727;4655.37152484417;4658.95341046592;4699.624030077585;4700.271457924027;5693.28833895763;5695.943040569624;5701.4298570142655;5702.657840529581;5706.828795136153;1404.4873621128215;1416.652920034598;1417.666848290651;1417.7873319002233;1422.0330175480772;931.3061768982152;934.3973137326386;945.4185675809327;970.1753509516996;970.5972021611094;4970.478099799541;4981.800727970841;4984;4986.675866068694;5008.248762685554;5874.941250587525;5904.409559044061;5913.142594323799;5919.496842768327;5923.674197919156;5002.824901128443;5003.124453220672;5003.924941125886;5005.624578156653;5008.774605142778;3105.0529588475733;3151.306712523287;3194.5847039884666;3195.137312925497;3195.4887218045465;5578.63738856973;5596.776128954878;5603.467670571289;5604.579656525777;5608.242887210175;6072.969635151796;6074.969625151846;6079.604825686405;6079.72010496064;6083.543734219863;5457;5568.582356323292;5599.80400685974;5605.8037968671715;5611.607187496836;5885.411458854252;5886.705664716661;5890.087036509348;5895.997680394257;5896.852578685395;5755.7697692093525;5765.279340082489;5767.048437008015;5769.307683078057;5771.769129234784;5568.972155139199;5585.888282234414;5589.524890384363;5605.523530499953;5608.186812912187;3812.790296533606;3948.9407658884566;3950.9012274693364;3989.6409323161056;3992.840286388512;5690;5841.1238314253205;5895.997680394429;5898.469137777621;5901.498372638373;5616.943830561642;5732;5752.9137062943255;5759.452851979056;5760.94239057604;5672.064109421899;5722.971385143214;5728.713564321851;5737.540996720336;5746.683932383759;5458.91811622818;5607.775688972395;5624.718764061862;5626;5626.127950167731;5778.8266352009505;5781.8554536136935;5786.07422812348;5787.826365209051;5794.681292528951;5645.237892884572;5647.689377084135;5649.039663257174;5656.660600363992;5657.60396772222 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: |5;yui.html;542.8941356435495;568.7923907773661;596.439347013807;615.5045188623158;617.6047329708985;10606.57573697052;10682.092022178105;10726.624568140116;10755.354678719283;10808.189385796068;10055.949720251388;10491.475426228677;10509.211809114327;10538.841917371226;10548.630797922075;273.80833416608357;277.13533774623204;491.46184927504413;498.95259950304666;564.1819361925204;564.266453610305;565.2256408720059;570.6205373426687;575.7265298982992;576.3631187537774;514.1259858240986;515.8194631878831;518.6291801362025;520.2352541763602;522;278.94281672257137;282.04106039465773;586.7154430101418;588.4644973074525;590.8404730722681;429.6090557592607;429.7163871844568;433.26561478294303;434.52202577165184;434.9978250108761;10703.357798532114;10812.72968175794;10832.945835270852;10839.83740243897;10846.023857852831;10084;10350.223733219964;10508.78982420355;10654.307470014424;10686.893131068668;8264.256216940445;8368.37237207212;8430.957845210734;8434.198751118634;8483.703070392568;3091.8763249469994;3109.6890310968947;3122.469180239342;3126.499760038383;3158.2893848884046;41136.177276454604;41435.89230807702;41529;42237.944051398656;42253.36619950703;8298.8340233195;8303.584820758937;8367.121452247547;8440.66237350499;8462.19609137131;14183.078099923472;14238;14239.074460160056;14275.286235688174;14295.57113286603;5747.942520574825;5782.132680098019;5819.272590926134;5852.092925596539;5853.678047707332;3374.5613070300706;3411.300683359905;3449.0515108345407;3463.2207753255316;3529.735269854771;2346.636271377905;2381.904723811063;2383.5947888858936;2390.259019703897;2405.086067294417;418.27011864297;420.1198489165194;423.14524660186254;424.3147317082889;426.2306536701222;464.25487093215577;499.7825945713626;501.79426435161406;521.5306224398072;522.0837430309782;6652.70062847173;6711.832204194837;6720.798376048726;6821.079154314203;6980.127484064492;341.9333230020172;391.94316824060354;446.70740664864564;507.3784613848007;523.1237676891192;3264.053424506914;3324.4182268102986;3393.7285017198565;3559.4660800880074;3874.6900247980093;49256;49272;49482;49509.50490495121;49589.25616115761;41409.378859317134;41425.37861932013;43680.56319436828;44298.55701443009;45659.315110273375;41405.585944140774;42103;42862.78568607199;42973.570264296955;43055.78472107682;6871.175458944958;7496.550206987598;7529.435292352986;7597.6581053853815;7650.349720273839;31197;32206.838965805957;32472;32699.509507356932;32976.505352419255;37733.622663774106;39117.69705332468;40729.38905916473;41053;41330;8060.637271322922;8888.911110888808;8992.415492992934;9015.819683606422;9023.90976090257;9692.660756873476;10410.583576656849;10651.201159912882;10749.355038697704;11102.111831053659;9484.241260699266;9699.951500242454;9748.463834489172;9875.703728888146;10160.491975401053;8785.341099417441;8854;8884.829610109538;8996.370254082152;9068.138526839939;9293.814123717622;9494.715158545254;9513.096255855682;9771.267154963263;10133.290669653054;8460.873086903825;8597.613107410056;8762;8816;8816.250618697482;8182.672693092448;8431.241188293083;8448.155184481502;8460.746177614681;8526.317894568543 03:27:59 INFO - 2015-11-26 03:27:59,699 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:27:59 INFO - 2015-11-26 03:27:59,700 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:27:59 INFO - 2015-11-26 03:27:59,700 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,947 03:27:59 INFO - 2015-11-26 03:27:59,700 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:27:59 INFO - 2015-11-26 03:27:59,700 DEBUG : BROWSER_OUTPUT: __startTimestamp1448537279690__endTimestamp 03:27:59 INFO - 2015-11-26 03:27:59,700 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,701 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:27:59 INFO - 2015-11-26 03:27:59,702 DEBUG : BROWSER_OUTPUT: Number of tests: 6 03:27:59 INFO - 2015-11-26 03:27:59,702 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,702 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4607.58 Median:4707.27 stddev:476.14 (10.1%) stddev-sans-first:451.67 03:27:59 INFO - 2015-11-26 03:27:59,702 DEBUG : BROWSER_OUTPUT: Values: 2631.6 2704.4 2707.4 2728.1 2743.9 4587.0 4684.0 4691.5 4699.2 4707.3 4591.8 4653.2 4695.0 4709.1 4722.8 4297.9 4309.0 4317.9 4335.2 4340.4 4153.0 4439.0 4442.7 4487.6 4491.5 4230.8 4321.9 4329.4 4332.4 4351.7 4309.2 4319.3 4325.4 4326.7 4369.2 4300.3 4313.5 4314.9 4337.0 4341.8 4495.3 4551.0 4568.1 4601.5 4646.3 5009.4 5038.6 5038.8 5044.9 5087.3 5070.0 5070.7 5076.4 5077.8 5129.5 4478.7 4571.0 4592.6 4593.7 4595.9 5141.1 5144.3 5146.2 5155.7 5191.2 4946.7 4953.2 4953.3 4956.1 4997.7 4647.7 4768.0 4792.1 4799.1 5027.0 4858.9 4884.3 4888.3 4894.1 4916.1 4938.1 4938.2 4951.7 4954.7 4982.9 3972.4 4059.5 4124.1 4152.5 4204.7 3593.6 3644.0 3645.8 3661.7 3678.9 4273.4 4300.5 4305.1 4308.2 4331.9 4829.6 4938.5 4999.8 5004.2 5060.6 4225.1 4270.8 4279.4 4290.9 4319.2 4458.5 4459.2 4464.0 4465.5 4497.6 4557.8 4659.7 4776.7 4781.9 4784.0 4868.1 4911.4 4978.8 4993.8 5039.9 4684.5 4735.0 4739.3 4744.9 4747.9 4751.9 4834.8 4934.2 4945.5 4991.5 4832.4 4954.8 5038.8 5044.1 5083.7 4912.2 4915.5 4931.9 4941.0 4983.9 4686.8 4823.8 4919.6 4923.4 4969.0 4681.1 4691.8 4696.6 4736.5 4768.4 4929.3 4930.3 4932.2 4942.9 4980.2 4610.3 4702.5 4830.4 4839.6 4879.1 03:27:59 INFO - 2015-11-26 03:27:59,703 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,703 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39296.27 Median:31564.37 stddev:73242.86 (232.0%) stddev-sans-first:73390.30 03:27:59 INFO - 2015-11-26 03:27:59,704 DEBUG : BROWSER_OUTPUT: Values: 463.2 474.4 507.6 509.7 514.8 21192.3 21651.1 31649.8 32391.0 32451.2 33576.5 33941.5 33958.5 33976.7 34018.3 6525.9 7586.3 7732.1 7750.1 7762.2 6549.5 9395.3 9526.7 9602.3 9630.1 9586.2 9658.7 9704.6 9704.9 9711.3 6598.4 6820.4 9354.5 9391.4 9633.3 9397.1 9538.2 9545.2 9547.2 9549.2 31162.8 31462.8 31542.4 31586.4 31619.7 2465.4 2642.5 2868.8 3014.6 3148.3 24434.0 24743.0 25103.4 25255.6 25560.4 3246.5 3351.9 3371.3 3387.9 3418.0 226351.2 226870.0 227624.0 228073.0 228360.0 31588.5 32373.8 39045.2 39253.4 45542.8 43645.8 43851.0 43885.3 43897.8 43992.8 11286.4 11324.9 11375.4 11401.3 11448.0 9150.3 9158.9 9253.8 9288.1 9294.2 14263.8 14518.1 14664.6 14740.5 14843.0 5852.5 5982.9 5987.9 6009.9 6013.7 720.0 729.6 883.0 895.0 896.3 22101.8 22310.1 22397.6 22409.2 22528.8 834.8 883.1 885.5 898.7 904.6 11662.9 12007.6 12027.4 12043.3 12054.0 265138.0 269449.0 270190.0 270244.0 270934.0 36511.5 36683.3 36723.0 36822.8 37017.8 997.2 1013.7 1030.6 1030.7 1036.8 21966.1 22364.0 22755.4 22777.0 22851.4 24395.5 24644.5 24650.0 32882.5 33712.5 322184.0 327947.0 330145.0 330301.0 332105.0 24011.5 24119.5 24342.4 24346.0 24361.9 22054.2 23361.0 25682.1 30988.2 32707.3 24021.5 24111.9 24148.8 24588.0 24886.4 25566.7 25912.5 26110.3 26188.1 26251.6 27048.6 27141.9 27349.9 27611.4 27664.7 25238.4 25322.9 25683.4 25868.9 26183.5 18434.4 18522.1 18715.3 18789.5 20741.7 03:27:59 INFO - 2015-11-26 03:27:59,704 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,706 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25391.64 Median:42684.07 stddev:78019.51 (182.8%) stddev-sans-first:78215.66 03:27:59 INFO - 2015-11-26 03:27:59,706 DEBUG : BROWSER_OUTPUT: Values: 320.9 323.3 324.2 324.6 325.5 11407.0 11796.9 13899.9 14161.8 14362.6 12984.2 13304.5 13329.3 13814.1 13967.0 4478.4 4576.0 4577.9 4581.5 4876.1 3268.2 3350.3 5057.5 5098.7 5268.2 5232.9 5234.2 5236.5 5253.3 5259.0 5136.5 5234.9 5573.8 5596.3 5614.3 4983.0 4983.1 4987.0 4994.5 5001.7 11685.4 11703.9 11731.4 11733.2 15241.6 1661.2 1704.2 1712.3 1719.4 1880.6 8514.5 8897.3 8943.1 10807.8 11705.8 1659.8 1681.4 1710.2 1721.6 1773.0 54239.7 56985.1 57018.0 57553.0 57631.7 8960.2 9003.2 9360.4 9574.6 9660.8 11936.2 12037.8 12125.2 12153.8 12157.9 6376.5 6507.0 6533.5 6535.0 6576.8 6115.6 6123.0 6140.4 6161.9 6178.4 3944.9 4013.6 4018.7 4034.5 4084.1 1445.4 1493.9 1567.9 1637.7 1637.9 626.7 699.8 712.8 760.4 792.3 13227.4 13511.4 13598.7 13748.4 13922.7 549.0 711.8 714.2 714.3 741.1 4988.3 7891.5 7895.3 8032.6 8035.4 467758.0 472518.0 477008.0 477130.0 490587.0 70232.0 70540.6 70705.6 70707.6 71380.0 882.2 883.1 888.8 892.1 908.8 12681.5 12714.2 12881.7 13045.7 13181.5 30276.0 30392.7 31362.4 31553.7 31752.8 42153.0 42372.8 42995.4 44565.6 44921.6 8870.2 8903.6 8996.9 8998.9 9032.1 10292.5 12929.3 15428.0 15484.6 15620.5 12425.6 12711.7 12758.5 12766.7 13042.1 12404.8 12764.4 13034.0 13145.3 13343.4 12028.0 12384.6 12444.7 12523.4 12897.0 8654.4 8697.3 8707.3 8876.4 8881.9 12018.2 12054.8 12460.7 12567.1 12788.4 03:27:59 INFO - 2015-11-26 03:27:59,706 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,706 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4700.60 Median:5891.03 stddev:2457.49 (41.7%) stddev-sans-first:2447.62 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: Values: 883.6 1137.6 1165.6 1171.2 1297.3 5311.4 5695.8 6655.3 6809.1 6822.0 6805.1 6837.7 6890.4 6903.5 6912.4 1678.4 1724.1 2054.6 2089.0 2089.4 1698.5 2041.1 2084.8 2086.6 2088.4 2015.0 2038.4 2044.7 2047.8 2059.0 1966.9 1998.5 2001.3 2002.9 2003.7 2032.1 2065.6 2067.2 2069.4 2070.1 6786.2 6851.7 6864.3 6868.7 6878.3 3918.4 3947.4 3977.2 3997.3 4012.0 2705.4 2735.2 2747.8 2757.8 2758.9 857.5 862.9 893.4 894.8 897.8 9211.0 9212.8 9228.4 9234.3 9240.4 3839.8 3887.0 3904.4 3912.3 3976.6 8033.3 8038.4 8055.3 8055.6 8056.2 4017.8 4023.7 4030.1 4033.3 4034.4 3654.8 3659.4 3660.6 3663.2 3667.3 2703.4 2750.6 3228.2 3289.7 3300.2 1005.1 1041.6 1050.7 1057.8 1061.3 761.4 885.9 907.4 908.1 910.0 6317.6 6390.6 6392.4 6396.0 6398.2 890.7 901.1 906.7 909.5 917.4 4529.4 4593.5 4597.0 4599.9 4603.2 7932.7 8097.4 8117.6 8162.4 8479.2 7992.8 8054.0 8068.9 8101.7 8108.9 6147.5 6159.2 6203.7 6252.0 6267.7 6243.5 6299.6 6307.1 6354.3 6357.3 7249.9 7389.7 7449.3 7502.7 7547.7 7553.1 7621.0 7642.8 7681.2 7749.9 5825.6 5847.0 5863.1 5870.1 5880.8 6194.5 6219.6 6247.1 6422.1 6426.1 5757.7 5893.4 5946.7 6055.4 6066.3 5754.7 5775.4 5811.4 5888.6 5919.6 5897.4 5906.4 5913.3 5923.4 5931.9 5462.0 5493.6 5537.8 5569.1 5572.5 5578.2 5582.3 5596.3 5597.4 5623.2 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4699.17 Median:5442.09 stddev:1410.15 (25.9%) stddev-sans-first:1374.71 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: Values: 289.0 292.8 294.6 295.3 297.0 5437.7 5446.5 5464.3 5507.2 5533.1 5388.4 5390.0 5394.4 5399.8 5403.5 4172.1 4198.4 4200.1 4200.4 4200.5 3943.0 4059.1 4069.5 4147.9 4148.2 4095.8 4107.2 4107.4 4116.3 4120.9 3875.3 4008.7 4156.8 4158.5 4172.7 3508.0 3620.4 3643.8 3645.1 3654.9 5145.3 5173.9 5204.9 5214.8 5222.6 4782.0 4795.9 4796.5 4796.8 4798.9 5450.2 5681.2 5686.9 5687.6 5692.3 3879.6 3884.1 3885.8 3887.3 3888.5 5919.7 5962.6 5983.1 6017.8 6020.9 3980.2 3987.5 3991.8 3991.8 3993.4 3739.5 3771.3 4040.7 4043.8 4050.7 4508.6 4655.4 4659.0 4699.6 4700.3 5693.3 5695.9 5701.4 5702.7 5706.8 1404.5 1416.7 1417.7 1417.8 1422.0 931.3 934.4 945.4 970.2 970.6 4970.5 4981.8 4984.0 4986.7 5008.2 5874.9 5904.4 5913.1 5919.5 5923.7 5002.8 5003.1 5003.9 5005.6 5008.8 3105.1 3151.3 3194.6 3195.1 3195.5 5578.6 5596.8 5603.5 5604.6 5608.2 6073.0 6075.0 6079.6 6079.7 6083.5 5457.0 5568.6 5599.8 5605.8 5611.6 5885.4 5886.7 5890.1 5896.0 5896.9 5755.8 5765.3 5767.0 5769.3 5771.8 5569.0 5585.9 5589.5 5605.5 5608.2 3812.8 3948.9 3950.9 3989.6 3992.8 5690.0 5841.1 5896.0 5898.5 5901.5 5616.9 5732.0 5752.9 5759.5 5760.9 5672.1 5723.0 5728.7 5737.5 5746.7 5458.9 5607.8 5624.7 5626.0 5626.1 5778.8 5781.9 5786.1 5787.8 5794.7 5645.2 5647.7 5649.0 5656.7 5657.6 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: 03:27:59 INFO - 2015-11-26 03:27:59,707 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11784.05 Median:22372.63 stddev:14068.02 (62.9%) stddev-sans-first:14082.16 03:27:59 INFO - 2015-11-26 03:27:59,709 DEBUG : BROWSER_OUTPUT: Values: 542.9 568.8 596.4 615.5 617.6 10606.6 10682.1 10726.6 10755.4 10808.2 10055.9 10491.5 10509.2 10538.8 10548.6 273.8 277.1 491.5 499.0 564.2 564.3 565.2 570.6 575.7 576.4 514.1 515.8 518.6 520.2 522.0 278.9 282.0 586.7 588.5 590.8 429.6 429.7 433.3 434.5 435.0 10703.4 10812.7 10832.9 10839.8 10846.0 10084.0 10350.2 10508.8 10654.3 10686.9 8264.3 8368.4 8431.0 8434.2 8483.7 3091.9 3109.7 3122.5 3126.5 3158.3 41136.2 41435.9 41529.0 42237.9 42253.4 8298.8 8303.6 8367.1 8440.7 8462.2 14183.1 14238.0 14239.1 14275.3 14295.6 5747.9 5782.1 5819.3 5852.1 5853.7 3374.6 3411.3 3449.1 3463.2 3529.7 2346.6 2381.9 2383.6 2390.3 2405.1 418.3 420.1 423.1 424.3 426.2 464.3 499.8 501.8 521.5 522.1 6652.7 6711.8 6720.8 6821.1 6980.1 341.9 391.9 446.7 507.4 523.1 3264.1 3324.4 3393.7 3559.5 3874.7 49256.0 49272.0 49482.0 49509.5 49589.3 41409.4 41425.4 43680.6 44298.6 45659.3 41405.6 42103.0 42862.8 42973.6 43055.8 6871.2 7496.6 7529.4 7597.7 7650.3 31197.0 32206.8 32472.0 32699.5 32976.5 37733.6 39117.7 40729.4 41053.0 41330.0 8060.6 8888.9 8992.4 9015.8 9023.9 9692.7 10410.6 10651.2 10749.4 11102.1 9484.2 9700.0 9748.5 9875.7 10160.5 8785.3 8854.0 8884.8 8996.4 9068.1 9293.8 9494.7 9513.1 9771.3 10133.3 8460.9 8597.6 8762.0 8816.0 8816.3 8182.7 8431.2 8448.2 8460.7 8526.3 03:27:59 INFO - 2015-11-26 03:27:59,709 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:27:59 INFO - 2015-11-26 03:27:59,709 DEBUG : BROWSER_OUTPUT: 03:28:00 INFO - 2015-11-26 03:28:00,413 INFO : Browser exited with error code: 0 03:28:00 INFO - 2015-11-26 03:28:00,432 INFO : Completed test dromaeo_css (00:18:36) 03:28:00 INFO - 2015-11-26 03:28:00,432 INFO : Starting test kraken 03:28:00 INFO - 2015-11-26 03:28:00,432 DEBUG : operating with platform_type : linux_ 03:28:00 INFO - 2015-11-26 03:28:00,432 INFO : Initialising browser for kraken test... 03:28:00 INFO - 2015-11-26 03:28:00,439 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb8131y/profile http://localhost:46622/getInfo.html 03:28:04 INFO - 2015-11-26 03:28:04,382 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:28:04 INFO - 2015-11-26 03:28:04,382 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:28:04 INFO - 2015-11-26 03:28:04,382 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:28:04 INFO - 2015-11-26 03:28:04,382 DEBUG : BROWSER_OUTPUT: __metrics 03:28:04 INFO - 2015-11-26 03:28:04,503 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] 03:28:05 INFO - 2015-11-26 03:28:05,859 INFO : Browser initialized. 03:28:05 INFO - 2015-11-26 03:28:05,859 INFO : Running cycle 1/1 for kraken test... 03:28:05 INFO - 2015-11-26 03:28:05,859 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb8131y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:28:17 INFO - 2015-11-26 03:28:17,802 DEBUG : BROWSER_OUTPUT: RSS: Main: 141217792 03:28:17 INFO - 2015-11-26 03:28:17,803 DEBUG : BROWSER_OUTPUT: 03:28:20 INFO - 2015-11-26 03:28:20,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46622/tests/kraken/driver.html?testName=audio-beat-detection) 03:28:20 INFO - 2015-11-26 03:28:20,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 176791552 03:28:20 INFO - 2015-11-26 03:28:20,981 DEBUG : BROWSER_OUTPUT: 03:28:23 INFO - 2015-11-26 03:28:23,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46622/tests/kraken/driver.html?testName=audio-dft) 03:28:24 INFO - 2015-11-26 03:28:24,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 166002688 03:28:24 INFO - 2015-11-26 03:28:24,286 DEBUG : BROWSER_OUTPUT: 03:28:27 INFO - 2015-11-26 03:28:27,308 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46622/tests/kraken/driver.html?testName=audio-fft) 03:28:27 INFO - 2015-11-26 03:28:27,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 149561344 03:28:27 INFO - 2015-11-26 03:28:27,882 DEBUG : BROWSER_OUTPUT: 03:28:29 INFO - 2015-11-26 03:28:29,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46622/tests/kraken/driver.html?testName=audio-oscillator) 03:28:30 INFO - 2015-11-26 03:28:30,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 153092096 03:28:30 INFO - 2015-11-26 03:28:30,469 DEBUG : BROWSER_OUTPUT: 03:28:32 INFO - 2015-11-26 03:28:32,707 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46622/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:28:33 INFO - 2015-11-26 03:28:33,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 154857472 03:28:33 INFO - 2015-11-26 03:28:33,288 DEBUG : BROWSER_OUTPUT: 03:28:36 INFO - 2015-11-26 03:28:36,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46622/tests/kraken/driver.html?testName=imaging-darkroom) 03:28:36 INFO - 2015-11-26 03:28:36,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 155471872 03:28:36 INFO - 2015-11-26 03:28:36,875 DEBUG : BROWSER_OUTPUT: 03:28:40 INFO - 2015-11-26 03:28:40,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46622/tests/kraken/driver.html?testName=imaging-desaturate) 03:28:40 INFO - 2015-11-26 03:28:40,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 155955200 03:28:40 INFO - 2015-11-26 03:28:40,861 DEBUG : BROWSER_OUTPUT: 03:28:43 INFO - 2015-11-26 03:28:43,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46622/tests/kraken/driver.html?testName=json-parse-financial) 03:28:44 INFO - 2015-11-26 03:28:44,308 DEBUG : BROWSER_OUTPUT: RSS: Main: 156680192 03:28:44 INFO - 2015-11-26 03:28:44,309 DEBUG : BROWSER_OUTPUT: 03:28:46 INFO - 2015-11-26 03:28:46,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46622/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:28:46 INFO - 2015-11-26 03:28:46,588 DEBUG : BROWSER_OUTPUT: RSS: Main: 168939520 03:28:46 INFO - 2015-11-26 03:28:46,589 DEBUG : BROWSER_OUTPUT: 03:28:48 INFO - 2015-11-26 03:28:48,507 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:28:49 INFO - 2015-11-26 03:28:49,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 166821888 03:28:49 INFO - 2015-11-26 03:28:49,100 DEBUG : BROWSER_OUTPUT: 03:28:51 INFO - 2015-11-26 03:28:51,204 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:28:51 INFO - 2015-11-26 03:28:51,778 DEBUG : BROWSER_OUTPUT: RSS: Main: 177168384 03:28:51 INFO - 2015-11-26 03:28:51,778 DEBUG : BROWSER_OUTPUT: 03:28:54 INFO - 2015-11-26 03:28:54,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:28:54 INFO - 2015-11-26 03:28:54,872 DEBUG : BROWSER_OUTPUT: RSS: Main: 181231616 03:28:54 INFO - 2015-11-26 03:28:54,872 DEBUG : BROWSER_OUTPUT: 03:28:57 INFO - 2015-11-26 03:28:57,508 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:28:58 INFO - 2015-11-26 03:28:58,080 DEBUG : BROWSER_OUTPUT: RSS: Main: 180436992 03:28:58 INFO - 2015-11-26 03:28:58,081 DEBUG : BROWSER_OUTPUT: 03:28:59 INFO - 2015-11-26 03:28:59,871 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46622/tests/kraken/driver.html?testName=ai-astar) 03:29:00 INFO - 2015-11-26 03:29:00,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 238206976 03:29:00 INFO - 2015-11-26 03:29:00,115 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,119 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:29:00 INFO - 2015-11-26 03:29:00,119 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:29:00 INFO - 2015-11-26 03:29:00,119 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;115;106;112;113;112;102;114;112;102 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;137;161;174;136;154;156;131;171;136 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;163;161;160;161;161;163;163;161;161 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;86;89;71;73;71;111;90;74 03:29:00 INFO - 2015-11-26 03:29:00,120 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;136;95;95;95;133;95;95;130 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;109;109;110;110;110;109 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;147;146;147;146;148;148;146;146 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;96;95;95;95 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;69;70;69;70;70;70;69;69;70;68 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;70;70;69;69;69;69;70;69 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;83;85;84;84;84;83;82;84;83 03:29:00 INFO - 2015-11-26 03:29:00,121 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;123;127;119;118;140;118;124;121;120 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;154;139;150;156;147;145;149;153;137;136 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;70;71;71;68;69;68;71;68;68;69 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: __start_cc_report 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,978 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: __end_cc_report 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: __startTimestamp1448537340117__endTimestamp 03:29:00 INFO - 2015-11-26 03:29:00,122 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: Number of tests: 14 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.10 Median:112.50 stddev:4.89 (4.3%) stddev-sans-first:5.07 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: Values: 113.0 115.0 106.0 112.0 113.0 112.0 102.0 114.0 112.0 102.0 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,126 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.60 Median:155.00 stddev:15.31 (9.9%) stddev-sans-first:16.23 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: Values: 150.0 137.0 161.0 174.0 136.0 154.0 156.0 131.0 171.0 136.0 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.40 Median:161.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: Values: 160.0 163.0 161.0 160.0 161.0 161.0 163.0 163.0 161.0 161.0 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.00 Median:73.50 stddev:13.03 (17.7%) stddev-sans-first:13.41 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 86.0 89.0 71.0 73.0 71.0 111.0 90.0 74.0 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:95.00 stddev:18.41 (19.4%) stddev-sans-first:19.06 03:29:00 INFO - 2015-11-26 03:29:00,127 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 136.0 95.0 95.0 95.0 133.0 95.0 95.0 130.0 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 109.0 109.0 110.0 110.0 110.0 109.0 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 147.0 146.0 147.0 146.0 148.0 148.0 146.0 146.0 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:29:00 INFO - 2015-11-26 03:29:00,128 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:69.40 Median:70.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 70.0 70.0 70.0 69.0 69.0 70.0 68.0 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.50 stddev:0.52 (0.7%) stddev-sans-first:0.53 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 70.0 69.0 69.0 69.0 69.0 70.0 69.0 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,129 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: Values: 83.0 83.0 85.0 84.0 84.0 84.0 83.0 82.0 84.0 83.0 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:122.00 stddev:6.62 (5.4%) stddev-sans-first:6.93 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: Values: 120.0 123.0 127.0 119.0 118.0 140.0 118.0 124.0 121.0 120.0 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.60 Median:149.50 stddev:7.20 (4.8%) stddev-sans-first:7.12 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: Values: 154.0 139.0 150.0 156.0 147.0 145.0 149.0 153.0 137.0 136.0 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.50 stddev:1.34 (1.9%) stddev-sans-first:1.39 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: Values: 70.0 71.0 71.0 68.0 69.0 68.0 71.0 68.0 68.0 69.0 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:29:00 INFO - 2015-11-26 03:29:00,130 DEBUG : BROWSER_OUTPUT: 03:29:00 INFO - 2015-11-26 03:29:00,989 INFO : Browser exited with error code: 0 03:29:01 INFO - 2015-11-26 03:29:01,041 INFO : Completed test kraken (00:01:00) 03:29:01 INFO - 2015-11-26 03:29:01,041 INFO : Starting test v8_7 03:29:01 INFO - 2015-11-26 03:29:01,041 DEBUG : operating with platform_type : linux_ 03:29:01 INFO - 2015-11-26 03:29:01,041 INFO : Initialising browser for v8_7 test... 03:29:01 INFO - 2015-11-26 03:29:01,048 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpue5d3P/profile http://localhost:46622/getInfo.html 03:29:05 INFO - 2015-11-26 03:29:05,030 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:29:05 INFO - 2015-11-26 03:29:05,030 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:29:05 INFO - 2015-11-26 03:29:05,031 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 03:29:05 INFO - 2015-11-26 03:29:05,031 DEBUG : BROWSER_OUTPUT: __metrics 03:29:05 INFO - 2015-11-26 03:29:05,188 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] 03:29:06 INFO - 2015-11-26 03:29:06,417 INFO : Browser initialized. 03:29:06 INFO - 2015-11-26 03:29:06,418 INFO : Running cycle 1/1 for v8_7 test... 03:29:06 INFO - 2015-11-26 03:29:06,418 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpue5d3P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:29:18 INFO - 2015-11-26 03:29:18,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 139403264 03:29:18 INFO - 2015-11-26 03:29:18,263 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,717 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46622/tests/v8_7/run.html (next: http://localhost:46622/tests/v8_7/run.html) 03:29:39 INFO - 2015-11-26 03:29:39,967 DEBUG : BROWSER_OUTPUT: RSS: Main: 441765888 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: __start_tp_report 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 03:29:39 INFO - 2015-11-26 03:29:39,968 DEBUG : BROWSER_OUTPUT: |0;Richards;165.28925619834712 03:29:39 INFO - 2015-11-26 03:29:39,969 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.27071716977505 03:29:39 INFO - 2015-11-26 03:29:39,969 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.8489289740699 03:29:39 INFO - 2015-11-26 03:29:39,969 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5186.5284974093265 03:29:39 INFO - 2015-11-26 03:29:39,970 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2012.0724346076458 03:29:39 INFO - 2015-11-26 03:29:39,970 DEBUG : BROWSER_OUTPUT: |5;Earley;798.0845969672786 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: |6;Boyer;12407.407407407407 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: |7;RegExp;26763.157894736843 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: |8;Splay;613.9279169211973 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: __end_tp_report 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: __startTimestamp1448537379967__endTimestamp 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,971 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: Number of tests: 10 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.29 Median:165.29 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: Values: 165.3 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.27 Median:192.27 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,972 DEBUG : BROWSER_OUTPUT: Values: 192.3 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.85 Median:281.85 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: Values: 281.8 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: Values: 5186.5 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,973 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2012.07 Median:2012.07 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: Values: 2012.1 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:798.08 Median:798.08 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: Values: 798.1 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12407.41 Median:12407.41 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,974 DEBUG : BROWSER_OUTPUT: Values: 12407.4 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26763.16 Median:26763.16 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: Values: 26763.2 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:613.93 Median:613.93 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: Values: 613.9 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: 03:29:39 INFO - 2015-11-26 03:29:39,975 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 03:29:39 INFO - 2015-11-26 03:29:39,976 DEBUG : BROWSER_OUTPUT: Values: 4845.4 03:29:39 INFO - 2015-11-26 03:29:39,976 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 03:29:39 INFO - 2015-11-26 03:29:39,976 DEBUG : BROWSER_OUTPUT: 03:29:40 INFO - 2015-11-26 03:29:40,866 INFO : Browser exited with error code: 0 03:29:40 INFO - 2015-11-26 03:29:40,880 INFO : Completed test v8_7 (00:00:39) 03:29:40 INFO - 2015-11-26 03:29:40,961 INFO : Completed test suite (00:20:17) 03:29:40 INFO - 2015-11-26 03:29:40,961 DEBUG : Working with test: dromaeo_css 03:29:40 INFO - 2015-11-26 03:29:40,961 DEBUG : Generating results file: dromaeo_css 03:29:40 INFO - 2015-11-26 03:29:40,962 DEBUG : Working with test: kraken 03:29:40 INFO - 2015-11-26 03:29:40,962 DEBUG : Generating results file: kraken 03:29:40 INFO - 2015-11-26 03:29:40,963 INFO : javascript benchmark 03:29:40 INFO - 2015-11-26 03:29:40,963 DEBUG : Working with test: v8_7 03:29:40 INFO - 2015-11-26 03:29:40,963 DEBUG : Generating results file: v8_7 03:29:40 INFO - 2015-11-26 03:29:40,963 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:29:41 INFO - 2015-11-26 03:29:41,020 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,35]] 03:29:41 INFO - 2015-11-26 03:29:41,021 DEBUG : process_Request line: dromaeo_css 5900.37 graph.html#tests=[[72,132,35]] 03:29:41 INFO - 2015-11-26 03:29:41,021 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:29:41 INFO - 2015-11-26 03:29:41,052 DEBUG : process_Request line: kraken 1522.10 graph.html#tests=[[232,132,35]] 03:29:41 INFO - 2015-11-26 03:29:41,052 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:29:41 INFO - 2015-11-26 03:29:41,084 DEBUG : process_Request line: v8_7 19619.62 graph.html#tests=[[230,132,35]] 03:29:41 INFO - 2015-11-26 03:29:41,085 INFO : javascript benchmark 03:29:41 INFO - 2015-11-26 03:29:41,089 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2631.644727961725, 2704.3509557706134, 2707.3773032202594, 2728.140635699753, 2743.8628068596563, 4587.0, 4684.0, 4691.507391723867, 4699.1776439123105, 4707.270373092168, 4591.816327346911, 4653.162430762462, 4695.014047050115, 4709.081729062826, 4722.795687099793, 4297.871063868089, 4309.030468144674, 4317.935230971522, 4335.241332766769, 4340.392345071693, 4152.97923510382, 4439.045605194882, 4442.733435993835, 4487.596116349528, 4491.528389519102, 4230.788460576959, 4321.913561728764, 4329.437173167499, 4332.415123958257, 4351.673624478177, 4309.2458819706735, 4319.33050377192, 4325.416068830699, 4326.6538676905775, 4369.169857727022, 4300.268954277749, 4313.525512193688, 4314.892127696801, 4337.0458499130455, 4341.761203133827, 4495.280755079204, 4550.954490455086, 4568.063546972862, 4601.493835678073, 4646.256598944186, 5009.373828271466, 5038.571721403685, 5038.79844806211, 5044.899102017976, 5087.262346959707, 5070.0, 5070.6957582545165, 5076.390833100015, 5077.796888124471, 5129.487051294878, 4478.708883922534, 4571.017231295221, 4592.563706447849, 4593.724376537419, 4595.931061034087, 5141.074606570854, 5144.279800827887, 5146.176611742103, 5155.74221288934, 5191.22131680251, 4946.727929963815, 4953.23224900141, 4953.306537084811, 4956.107900577858, 4997.700137991732, 4647.744374059459, 4767.976160119178, 4792.137415265216, 4799.136155491975, 5026.974865125651, 4858.854234372974, 4884.267359896044, 4888.26675998596, 4894.143524883131, 4916.065947470041, 4938.135826230396, 4938.247041677668, 4951.678140920829, 4954.677945933575, 4982.8754281143265, 3972.3842804365368, 4059.4925634295714, 4124.092699606082, 4152.5432202457705, 4204.726692764961, 3593.6226696197045, 3643.9635603644156, 3645.7630254033934, 3661.7436779425716, 3678.8528458861883, 4273.380359847804, 4300.548442413501, 4305.117450922553, 4308.159908817813, 4331.891702707458, 4829.5653391194965, 4938.456769755324, 4999.775010124553, 5004.199328107486, 5060.645754797129, 4225.070484493406, 4270.786460677016, 4279.358096285582, 4290.914181716348, 4319.157764236007, 4458.5318541553315, 4459.241928872099, 4463.977680111579, 4465.531119232433, 4497.617702495259, 4557.772111394351, 4659.673822832369, 4776.689515181502, 4781.880452988634, 4783.976080119577, 4868.07506573764, 4911.4106307243355, 4978.8257410990445, 4993.825216117419, 5039.899202016012, 4684.461286952044, 4735.029318989667, 4739.312799643963, 4744.92882606768, 4747.905041899212, 4751.904961900674, 4834.758262086952, 4934.235193545006, 4945.505449455026, 4991.47589503097, 4832.371791667201, 4954.801807927643, 5038.798448062036, 5044.1424957756535, 5083.72039537829, 4912.238603016538, 4915.5330243626795, 4931.876703082308, 4940.975295123501, 4983.925241121314, 4686.789094490824, 4823.782929768239, 4919.606431485544, 4923.384576927884, 4969.0, 4681.110588988081, 4691.812327506861, 4696.647751418725, 4736.455307639666, 4768.356271903249, 4929.260610908393, 4930.285108659296, 4932.186189278872, 4942.851714448572, 4980.203167493184, 4610.2623580227, 4702.52974702527, 4830.420349558076, 4839.588634965927, 4879.072976134523], "unit": "ms", "name": "dojo.html", "value": 4578.446301480224}, {"lowerIsBetter": false, "replicates": [463.1570541614261, 474.4093603463688, 507.6421123108208, 509.666168659528, 514.7812179823574, 21192.25827096052, 21651.133954641817, 31649.84175079124, 32391.0, 32451.188720281945, 33576.49635255473, 33941.490877636854, 33958.49062264062, 33976.660233397655, 34018.319633607316, 6525.93474065258, 7586.279303466162, 7732.072151341847, 7750.108737495176, 7762.223777622249, 6549.541532092755, 9395.295352848529, 9526.666566670134, 9602.327837051409, 9630.133288004079, 9586.233101351874, 9658.71023869285, 9704.563294651758, 9704.902950970505, 9711.320207585472, 6598.37315455031, 6820.3861652451515, 9354.532273386303, 9391.43651380916, 9633.325667203299, 9397.107274808883, 9538.236941044699, 9545.188658963927, 9547.236221102296, 9549.236061115094, 31162.84418577915, 31462.842685786425, 31542.36915261682, 31586.36827263465, 31619.683803161905, 2465.3590066582638, 2642.4582960493053, 2868.770498360147, 3014.608100946863, 3148.2916343822685, 24434.02852147578, 24743.0, 25103.37241568957, 25255.62116568253, 25560.360990975187, 3246.4643333849885, 3351.9497207541663, 3371.2920286739813, 3387.915302117442, 3417.9658203418144, 226351.23312052162, 226870.0, 227623.99999999834, 228073.0, 228360.0, 31588.52617210744, 32373.838130809196, 39045.219095617926, 39253.411198832044, 45542.77228613836, 43645.781771090944, 43851.0, 43885.341719874225, 43897.78051109788, 43992.78003609961, 11286.379249141377, 11324.886751132382, 11375.374354410587, 11401.258918170459, 11448.0, 9150.313726470542, 9158.86261706075, 9253.768655783524, 9288.11762882525, 9294.209992150607, 14263.786043209153, 14518.128912265298, 14664.633384165481, 14740.484083057041, 14843.0, 5852.502537284378, 5982.850428739229, 5987.9101813472835, 6009.939900600938, 6013.669248191304, 720.0315575550876, 729.584137041881, 883.0418995389946, 894.9550277615747, 896.3098414221014, 22101.778982210293, 22310.10759569599, 22397.55204895893, 22409.21567745121, 22528.774712252667, 834.7787836223406, 883.1433509495779, 885.4864178776294, 898.6809682562726, 904.5974541329045, 11662.94168529169, 12007.639770806889, 12027.398630068637, 12043.277403355827, 12054.0, 265138.0, 269449.0, 270190.0, 270244.0, 270934.0, 36511.4523282151, 36683.26633467316, 36723.0, 36822.8158859204, 37017.81491092581, 997.234894237196, 1013.7277716465788, 1030.6238223048683, 1030.6907927621833, 1036.8341065429645, 21966.121355146253, 22364.0, 22755.431114221614, 22777.0, 22851.42871428161, 24395.51208975846, 24644.507109857343, 24650.0, 32882.5067623981, 33712.49431258582, 322184.0, 327947.0, 330145.0, 330301.0, 332105.0, 24011.51976960486, 24119.51760964806, 24342.391440214138, 24346.0, 24361.878190608935, 22054.228102016354, 23361.0, 25682.101126460486, 30988.22529436782, 32707.34585308328, 24021.51956960816, 24111.879440602686, 24148.75851241465, 24588.0, 24886.37784055413, 25566.744332556435, 25912.48175036451, 26110.34724131912, 26188.08341708031, 26251.60622590701, 27048.594271086342, 27141.86429067842, 27349.86325068362, 27611.447771044066, 27664.723352766214, 25238.369040774127, 25322.873385632953, 25683.35791605225, 25868.889587292946, 26183.476330473666, 18434.354797582022, 18522.0738963054, 18715.25138994425, 18789.530261743566, 20741.68887466659], "unit": "ms", "name": "ext.html", "value": 14813.90741737092}, {"lowerIsBetter": false, "replicates": [320.90854106579627, 323.3371588244099, 324.24465733235076, 324.56022090067955, 325.4727341706436, 11407.0, 11796.941015294911, 13899.861001389983, 14161.787573186397, 14362.640933976656, 12984.156029858077, 13304.534341298056, 13329.33383327084, 13814.1020833646, 13967.0, 4478.350639157312, 4575.954240457603, 4577.862664120082, 4581.541845815408, 4876.073546026261, 3268.2319654881203, 3350.329934013207, 5057.468965758598, 5098.668586541863, 5268.1834315681135, 5232.8691782705355, 5234.162533994561, 5236.450172731866, 5253.3170687810725, 5259.000789849916, 5136.460671629481, 5234.947650523484, 5573.832785016456, 5596.3284405871145, 5614.29821272341, 4982.975085124587, 4983.127952608279, 4986.975065124688, 4994.475580064077, 5001.699898006095, 11685.357305348203, 11703.94148029263, 11731.413429328499, 11733.178677492579, 15241.6189595261, 1661.1611136376173, 1704.181992643538, 1712.2637265975625, 1719.4325872462057, 1880.6426778911964, 8514.531700756455, 8897.288216942694, 8943.06097859722, 10807.78384432307, 11705.824412633818, 1659.7593348964333, 1681.3862940026802, 1710.1876608610935, 1721.5782133377277, 1773.0336966353368, 54239.728801356134, 56985.1452228221, 57018.0, 57553.0, 57631.71184144094, 8960.238379737795, 9003.189712925869, 9360.4383736976, 9574.569144388519, 9660.806783864284, 11936.164468487123, 12037.819432708517, 12125.151239413333, 12153.756924861453, 12157.878421215852, 6376.458001069961, 6506.967465162738, 6533.477321814244, 6535.0, 6576.8355791105605, 6115.6330620162935, 6122.989706698345, 6140.4473597376455, 6161.938380616137, 6178.41305076017, 3944.861929832442, 4013.5585085640555, 4018.6584140347813, 4034.455348527911, 4084.101497670508, 1445.3712635003808, 1493.9402423903139, 1567.882408819343, 1637.7052130616603, 1637.8853480256269, 626.7179769103922, 699.8040548646387, 712.7576623947882, 760.3916866506772, 792.2908996448147, 13227.404766785517, 13511.391987360592, 13598.660033499242, 13748.381322840496, 13922.651933701738, 548.964317319373, 711.8362776561316, 714.1572943926217, 714.3106901839645, 741.1180694972965, 4988.326575912278, 7891.48705334163, 7895.340373129156, 8032.558209298545, 8035.397345199017, 467758.0, 472518.0, 477008.0, 477130.0, 490587.0, 70232.0, 70540.64729676422, 70705.64647176834, 70707.6464617684, 71380.0, 882.1619461511461, 883.0860059838037, 888.813349196663, 892.1123482135317, 908.7955210077812, 12681.492740290285, 12714.237145771283, 12881.677958050748, 13045.739085218052, 13181.538646147339, 30276.0, 30392.656807751573, 31362.372752545278, 31553.684463155074, 31752.841235793672, 42153.0, 42372.78813605912, 42995.35506967335, 44565.55434445614, 44921.55078449174, 8870.15733505316, 8903.643854245758, 8996.86504702443, 8998.910010899806, 9032.14194651507, 10292.536835842255, 12929.288889111189, 15428.0, 15484.612884677974, 15620.453284135001, 12425.565105221636, 12711.745765084832, 12758.48966041385, 12766.68083297925, 13042.087053906136, 12404.81392779091, 12764.361781910682, 13034.0, 13145.342732863128, 13343.399547020601, 12028.0, 12384.56654017105, 12444.688882778002, 12523.436445360163, 12897.0, 8654.394192406722, 8697.304215662858, 8707.303415726854, 8876.378653494452, 8881.911180888108, 12018.218815776947, 12054.819177712518, 12460.688482788002, 12567.120301578801, 12788.360581970679], "unit": "ms", "name": "jquery.html", "value": 7662.934921766039}, {"lowerIsBetter": false, "replicates": [883.6244596046679, 1137.550667486343, 1165.5920427850253, 1171.1743221029174, 1297.3253907967858, 5311.415744268127, 5695.772169113236, 6655.334466553349, 6809.148856392951, 6821.9658901705425, 6805.149356330458, 6837.726490940368, 6890.414314783249, 6903.516753827234, 6912.4470042396615, 1678.3706110208673, 1724.1379310344828, 2054.6198953193634, 2089.028601700209, 2089.3836318286144, 1698.5498842806667, 2041.1325186795627, 2084.7810979847127, 2086.62440760663, 2088.363049269972, 2014.969775453362, 2038.3681058871723, 2044.6524090904506, 2047.7645070816823, 2059.042545216474, 1966.8918209498472, 1998.490384951828, 2001.2995451591826, 2002.8798272103647, 2003.699445083242, 2032.095717405755, 2065.5662310914727, 2067.24545540878, 2069.3998740365278, 2070.130545171032, 6786.185657721056, 6851.691673874688, 6864.313568643146, 6868.690908909111, 6878.3465570770695, 3918.3730603103368, 3947.407888816672, 3977.1846771411792, 3997.34043882759, 4011.959880401217, 2705.4318593095472, 2735.193118030166, 2747.7801775858084, 2757.765589924859, 2758.8758505867286, 857.5455008845322, 862.9352798540135, 893.423741686615, 894.8120894612139, 897.7800438892448, 9211.0, 9212.815743685089, 9228.40015398997, 9234.30742694294, 9240.445573265626, 3839.8080095995088, 3886.9611303887164, 3904.4143378493454, 3912.3349030664854, 3976.5824588418377, 8033.277005069572, 8038.39712021589, 8055.275025247756, 8055.556944367999, 8056.194380562014, 4017.7991100445447, 4023.6781057515327, 4030.052937559671, 4033.3344998075577, 4034.3545032794614, 3654.798986055739, 3659.3779057560278, 3660.5790334111916, 3663.249033948034, 3667.339878821838, 2703.39173685923, 2750.6286651302216, 3228.1606782236786, 3289.671032896691, 3300.207950091961, 1005.0954141272913, 1041.5937784264142, 1050.6795427394718, 1057.8148823955778, 1061.3101484035285, 761.3566536276837, 885.8582626779685, 907.4419232172298, 908.1191244492832, 909.9545022748836, 6317.557770955989, 6390.616563006234, 6392.4246817786625, 6396.0405939109505, 6398.200224971879, 890.7461373508519, 901.0944001278673, 906.6871929184421, 909.5315912305168, 917.417439925652, 4529.433820772403, 4593.540645935446, 4596.954030459653, 4599.862004139881, 4603.240465323184, 7932.722354717557, 8097.39269554774, 8117.634706438106, 8162.3878209134555, 8479.19447652472, 7992.760217193493, 8053.959730201312, 8068.919310806817, 8101.716439924692, 8108.878366824384, 6147.538934579924, 6159.23009623797, 6203.658798766111, 6251.9687401562705, 6267.749290028439, 6243.469305109116, 6299.590526615755, 6307.148534947907, 6354.301026887131, 6357.268914074941, 7249.855002900018, 7389.667464964197, 7449.292317229854, 7502.662380192795, 7547.698092076255, 7553.0936287643635, 7621.047369078865, 7642.847143057219, 7681.193474685301, 7749.8837517438415, 5825.621334613236, 5847.0, 5863.120531920246, 5870.090136028923, 5880.8235752927485, 6194.535409844189, 6219.564630476004, 6247.125402443752, 6422.068800024063, 6426.100345951477, 5757.740901659352, 5893.410658934073, 5946.672932988728, 6055.364186760503, 6066.332703402534, 5754.68349240779, 5775.422457754191, 5811.389804070512, 5888.646681199141, 5919.5856290061, 5897.3807750187325, 5906.350301466749, 5913.290405151237, 5923.437273459014, 5931.9110213345975, 5461.972690136683, 5493.642913210629, 5537.8061767837935, 5569.10894256917, 5572.526335261548, 5578.163275508544, 5582.302212223472, 5596.328440586993, 5597.44025597437, 5623.2408624835125], "unit": "ms", "name": "mootools.html", "value": 3843.2330373841864}, {"lowerIsBetter": false, "replicates": [288.97399234068934, 292.7804146889833, 294.62435394871534, 295.27215414004473, 296.991090267292, 5437.667771396009, 5446.509814116734, 5464.26232458618, 5507.201455788908, 5533.059379905416, 5388.407275199725, 5389.973050134754, 5394.352677678684, 5399.811006614769, 5403.486668766471, 4172.102997855461, 4198.391233271173, 4200.075983283673, 4200.390943313218, 4200.474940632414, 3942.960570394288, 4059.106996460774, 4069.470968774055, 4147.875563733093, 4148.191102734968, 4095.75425474471, 4107.17856428714, 4107.404426358176, 4116.34138537834, 4120.855770048049, 3875.3218186817194, 4008.7372477669733, 4156.750594964312, 4158.480189976253, 4172.749635021908, 3507.982460087683, 3620.3664358737115, 3643.8178091095606, 3645.106948797535, 3654.908627284339, 5145.33110695611, 5173.896522069575, 5204.9219261711105, 5214.765335559871, 5222.63441559091, 4782.019685964369, 4795.8729698521165, 4796.496367881392, 4796.7841447134615, 4798.872265017753, 5450.236966824647, 5681.204631351614, 5686.943130568683, 5687.630304030266, 5692.345380281279, 3879.612038796126, 3884.1454879926373, 3885.805709714503, 3887.300285948528, 3888.5333759948985, 5919.704014799243, 5962.612430192024, 5983.072623743326, 6017.849553761147, 6020.909686354708, 3980.20395920817, 3987.4847557928383, 3991.780452075164, 3991.840326386912, 3993.4209539617173, 3739.476473293741, 3771.321162190832, 4040.676745860324, 4043.838246470108, 4050.736702114353, 4508.616767574727, 4655.37152484417, 4658.95341046592, 4699.624030077585, 4700.271457924027, 5693.28833895763, 5695.943040569624, 5701.4298570142655, 5702.657840529581, 5706.828795136153, 1404.4873621128215, 1416.652920034598, 1417.666848290651, 1417.7873319002233, 1422.0330175480772, 931.3061768982152, 934.3973137326386, 945.4185675809327, 970.1753509516996, 970.5972021611094, 4970.478099799541, 4981.800727970841, 4984.0, 4986.675866068694, 5008.248762685554, 5874.941250587525, 5904.409559044061, 5913.142594323799, 5919.496842768327, 5923.674197919156, 5002.824901128443, 5003.124453220672, 5003.924941125886, 5005.624578156653, 5008.774605142778, 3105.0529588475733, 3151.306712523287, 3194.5847039884666, 3195.137312925497, 3195.4887218045465, 5578.63738856973, 5596.776128954878, 5603.467670571289, 5604.579656525777, 5608.242887210175, 6072.969635151796, 6074.969625151846, 6079.604825686405, 6079.72010496064, 6083.543734219863, 5457.0, 5568.582356323292, 5599.80400685974, 5605.8037968671715, 5611.607187496836, 5885.411458854252, 5886.705664716661, 5890.087036509348, 5895.997680394257, 5896.852578685395, 5755.7697692093525, 5765.279340082489, 5767.048437008015, 5769.307683078057, 5771.769129234784, 5568.972155139199, 5585.888282234414, 5589.524890384363, 5605.523530499953, 5608.186812912187, 3812.790296533606, 3948.9407658884566, 3950.9012274693364, 3989.6409323161056, 3992.840286388512, 5690.0, 5841.1238314253205, 5895.997680394429, 5898.469137777621, 5901.498372638373, 5616.943830561642, 5732.0, 5752.9137062943255, 5759.452851979056, 5760.94239057604, 5672.064109421899, 5722.971385143214, 5728.713564321851, 5737.540996720336, 5746.683932383759, 5458.91811622818, 5607.775688972395, 5624.718764061862, 5626.0, 5626.127950167731, 5778.8266352009505, 5781.8554536136935, 5786.07422812348, 5787.826365209051, 5794.681292528951, 5645.237892884572, 5647.689377084135, 5649.039663257174, 5656.660600363992, 5657.60396772222], "unit": "ms", "name": "prototype.html", "value": 4238.518891434341}, {"lowerIsBetter": false, "replicates": [542.8941356435495, 568.7923907773661, 596.439347013807, 615.5045188623158, 617.6047329708985, 10606.57573697052, 10682.092022178105, 10726.624568140116, 10755.354678719283, 10808.189385796068, 10055.949720251388, 10491.475426228677, 10509.211809114327, 10538.841917371226, 10548.630797922075, 273.80833416608357, 277.13533774623204, 491.46184927504413, 498.95259950304666, 564.1819361925204, 564.266453610305, 565.2256408720059, 570.6205373426687, 575.7265298982992, 576.3631187537774, 514.1259858240986, 515.8194631878831, 518.6291801362025, 520.2352541763602, 522.0, 278.94281672257137, 282.04106039465773, 586.7154430101418, 588.4644973074525, 590.8404730722681, 429.6090557592607, 429.7163871844568, 433.26561478294303, 434.52202577165184, 434.9978250108761, 10703.357798532114, 10812.72968175794, 10832.945835270852, 10839.83740243897, 10846.023857852831, 10084.0, 10350.223733219964, 10508.78982420355, 10654.307470014424, 10686.893131068668, 8264.256216940445, 8368.37237207212, 8430.957845210734, 8434.198751118634, 8483.703070392568, 3091.8763249469994, 3109.6890310968947, 3122.469180239342, 3126.499760038383, 3158.2893848884046, 41136.177276454604, 41435.89230807702, 41529.0, 42237.944051398656, 42253.36619950703, 8298.8340233195, 8303.584820758937, 8367.121452247547, 8440.66237350499, 8462.19609137131, 14183.078099923472, 14238.0, 14239.074460160056, 14275.286235688174, 14295.57113286603, 5747.942520574825, 5782.132680098019, 5819.272590926134, 5852.092925596539, 5853.678047707332, 3374.5613070300706, 3411.300683359905, 3449.0515108345407, 3463.2207753255316, 3529.735269854771, 2346.636271377905, 2381.904723811063, 2383.5947888858936, 2390.259019703897, 2405.086067294417, 418.27011864297, 420.1198489165194, 423.14524660186254, 424.3147317082889, 426.2306536701222, 464.25487093215577, 499.7825945713626, 501.79426435161406, 521.5306224398072, 522.0837430309782, 6652.70062847173, 6711.832204194837, 6720.798376048726, 6821.079154314203, 6980.127484064492, 341.9333230020172, 391.94316824060354, 446.70740664864564, 507.3784613848007, 523.1237676891192, 3264.053424506914, 3324.4182268102986, 3393.7285017198565, 3559.4660800880074, 3874.6900247980093, 49256.0, 49272.0, 49482.0, 49509.50490495121, 49589.25616115761, 41409.378859317134, 41425.37861932013, 43680.56319436828, 44298.55701443009, 45659.315110273375, 41405.585944140774, 42103.0, 42862.78568607199, 42973.570264296955, 43055.78472107682, 6871.175458944958, 7496.550206987598, 7529.435292352986, 7597.6581053853815, 7650.349720273839, 31197.0, 32206.838965805957, 32472.0, 32699.509507356932, 32976.505352419255, 37733.622663774106, 39117.69705332468, 40729.38905916473, 41053.0, 41330.0, 8060.637271322922, 8888.911110888808, 8992.415492992934, 9015.819683606422, 9023.90976090257, 9692.660756873476, 10410.583576656849, 10651.201159912882, 10749.355038697704, 11102.111831053659, 9484.241260699266, 9699.951500242454, 9748.463834489172, 9875.703728888146, 10160.491975401053, 8785.341099417441, 8854.0, 8884.829610109538, 8996.370254082152, 9068.138526839939, 9293.814123717622, 9494.715158545254, 9513.096255855682, 9771.267154963263, 10133.290669653054, 8460.873086903825, 8597.613107410056, 8762.0, 8816.0, 8816.250618697482, 8182.672693092448, 8431.241188293083, 8448.155184481502, 8460.746177614681, 8526.317894568543], "unit": "ms", "name": "yui.html", "value": 4983.558177509141}], "name": "dromaeo_css", "value": 5900.369103657263}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 115.0, 106.0, 112.0, 113.0, 112.0, 102.0, 114.0, 112.0, 102.0], "unit": "ms", "name": "ai-astar", "value": 110.1}, {"lowerIsBetter": true, "replicates": [150.0, 137.0, 161.0, 174.0, 136.0, 154.0, 156.0, 131.0, 171.0, 136.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.6}, {"lowerIsBetter": true, "replicates": [160.0, 163.0, 161.0, 160.0, 161.0, 161.0, 163.0, 163.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.4}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 86.0, 89.0, 71.0, 73.0, 71.0, 111.0, 90.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 81.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 136.0, 95.0, 95.0, 95.0, 133.0, 95.0, 95.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 148.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 70.0, 70.0, 70.0, 69.0, 69.0, 70.0, 68.0], "unit": "ms", "name": "json-parse-financial", "value": 69.4}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 70.0, 69.0, 69.0, 69.0, 69.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 85.0, 84.0, 84.0, 84.0, 83.0, 82.0, 84.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 83.5}, {"lowerIsBetter": true, "replicates": [120.0, 123.0, 127.0, 119.0, 118.0, 140.0, 118.0, 124.0, 121.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.0}, {"lowerIsBetter": true, "replicates": [154.0, 139.0, 150.0, 156.0, 147.0, 145.0, 149.0, 153.0, 137.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.6}, {"lowerIsBetter": true, "replicates": [70.0, 71.0, 71.0, 68.0, 69.0, 68.0, 71.0, 68.0, 68.0, 69.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 69.3}], "name": "kraken", "value": 1522.1000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.28925619834712], "unit": "score", "name": "Richards", "value": 213.5771000000001}, {"lowerIsBetter": false, "replicates": [192.27071716977505], "unit": "score", "name": "DeltaBlue", "value": 343.87971799999985}, {"lowerIsBetter": false, "replicates": [281.8489289740699], "unit": "score", "name": "Encrypt", "value": 944.410188}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [2012.0724346076458], "unit": "score", "name": "RayTrace", "value": 367.77453300000013}, {"lowerIsBetter": false, "replicates": [798.0845969672786], "unit": "score", "name": "Earley", "value": 835.078139}, {"lowerIsBetter": false, "replicates": [12407.407407407407], "unit": "score", "name": "Boyer", "value": 53.71492835820899}, {"lowerIsBetter": false, "replicates": [26763.157894736843], "unit": "score", "name": "RegExp", "value": 34.0387708947886}, {"lowerIsBetter": false, "replicates": [613.9279169211973], "unit": "score", "name": "Splay", "value": 132.73708159203986}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 19619.621572559026}]} 03:29:41 INFO - RETURN: dromaeo_css: 5900.37 03:29:41 INFO - RETURN: kraken: 1522.10 03:29:41 INFO - RETURN: v8_7: 19619.62 03:29:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,35]]", "result": "19619.62"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,35]]", "result": "1522.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,35]]", "result": "5900.37"}}} 03:29:41 INFO - Return code: 0 03:29:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:29:41 INFO - # TBPL SUCCESS # 03:29:41 INFO - Running post-action listener: _resource_record_post_action 03:29:41 INFO - Running post-run listener: _resource_record_post_run 03:29:42 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517726208; Read time: 8; Write time: 496588 03:29:42 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 246349824; Read time: 0; Write time: 346660 03:29:42 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 271376384; Read time: 0; Write time: 149928 03:29:42 INFO - Running post-run listener: _upload_blobber_files 03:29:42 INFO - Blob upload gear active. 03:29:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 03:29:42 INFO - Copying logs to upload dir... 03:29:42 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.796098 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-11-26 03:29:42.048146) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:29:42.052125) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009877 build_url: 'https://queue.taskcluster.net/v1/task/-nBK_wL_SKe35DLIg0DX8Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-26 03:29:42.104834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:29:42.105188) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0c96bf5caf0e090009e1a122000001b2-1448535968.932217-2111570152 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-26 03:29:42.155581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 03:29:42.155941) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-26 03:29:42.156259) ========= ========= Total master_lag: 0.26 =========