builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-059 starttime: 1475081309.65 results: success (0) buildid: 20160928063004 builduid: c23f3af6c7c240c8a853bdacfb8c43bb revision: fc513f0359ebca43d8b791a458583aeb3bb37f42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.649473) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.650455) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.651180) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.669159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.669745) ========= 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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-28 09:48:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.3M=0s 2016-09-28 09:48:31 (50.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.814298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.814880) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018690 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:29.866554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:48:29.866933) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev fc513f0359ebca43d8b791a458583aeb3bb37f42 --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 fc513f0359ebca43d8b791a458583aeb3bb37f42 --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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-28 09:48:31,659 truncating revision to first 12 chars 2016-09-28 09:48:31,659 Setting DEBUG logging. 2016-09-28 09:48:31,659 attempt 1/10 2016-09-28 09:48:31,659 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/fc513f0359eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 09:48:32,407 unpacking tar archive at: fx-team-fc513f0359eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.105751 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 09:48:30.987613) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:30.988436) ========= script_repo_revision: fc513f0359ebca43d8b791a458583aeb3bb37f42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:30.989469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:30.990166) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:48:31.002875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-28 09:48:31.003555) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:32 INFO - MultiFileLogger online at 20160928 09:48:32 in /builds/slave/test 09:48:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:48:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:32 INFO - {'append_to_log': False, 09:48:32 INFO - 'base_work_dir': '/builds/slave/test', 09:48:32 INFO - 'blob_upload_branch': 'Fx-Team', 09:48:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:32 INFO - 'branch': 'Fx-Team', 09:48:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:32 INFO - 'config_files': ('talos/linux_config.py',), 09:48:32 INFO - 'default_actions': ('clobber', 09:48:32 INFO - 'read-buildbot-config', 09:48:32 INFO - 'download-and-extract', 09:48:32 INFO - 'populate-webroot', 09:48:32 INFO - 'create-virtualenv', 09:48:32 INFO - 'install', 09:48:32 INFO - 'run-tests'), 09:48:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:32 INFO - 'download_minidump_stackwalk': True, 09:48:32 INFO - 'download_symbols': 'ondemand', 09:48:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:32 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:32 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:32 INFO - 'installer_path': 'installer.exe', 09:48:32 INFO - 'log_level': 'info', 09:48:32 INFO - 'log_name': 'talos', 09:48:32 INFO - 'log_to_console': True, 09:48:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:32 INFO - 'opt_config_files': (), 09:48:32 INFO - 'pip_index': False, 09:48:32 INFO - 'sps_profile': False, 09:48:32 INFO - 'sps_profile_interval': 0, 09:48:32 INFO - 'suite': 'dromaeojs-e10s', 09:48:32 INFO - 'system_bits': '32', 09:48:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:32 INFO - 'title': 'talos-linux64-ix-059', 09:48:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:32 INFO - 'use_talos_json': True, 09:48:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:32 INFO - 'work_dir': 'build'} 09:48:32 INFO - [mozharness: 2016-09-28 16:48:32.890911Z] Running clobber step. 09:48:32 INFO - Running pre-action listener: _resource_record_pre_action 09:48:32 INFO - Running main action method: clobber 09:48:32 INFO - rmtree: /builds/slave/test/build 09:48:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:33 INFO - Running post-action listener: _resource_record_post_action 09:48:33 INFO - [mozharness: 2016-09-28 16:48:33.351790Z] Finished clobber step (success) 09:48:33 INFO - [mozharness: 2016-09-28 16:48:33.351891Z] Running read-buildbot-config step. 09:48:33 INFO - Running pre-action listener: _resource_record_pre_action 09:48:33 INFO - Running main action method: read_buildbot_config 09:48:33 INFO - Using buildbot properties: 09:48:33 INFO - { 09:48:33 INFO - "project": "", 09:48:33 INFO - "product": "firefox", 09:48:33 INFO - "script_repo_revision": "production", 09:48:33 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 09:48:33 INFO - "repository": "", 09:48:33 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 09:48:33 INFO - "buildid": "20160928063004", 09:48:33 INFO - "pgo_build": "True", 09:48:33 INFO - "basedir": "/builds/slave/test", 09:48:33 INFO - "buildnumber": 1207, 09:48:33 INFO - "slavename": "talos-linux64-ix-059", 09:48:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:48:33 INFO - "platform": "ubuntu64_hw", 09:48:33 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 09:48:33 INFO - "branch": "fx-team", 09:48:33 INFO - "repo_path": "integration/fx-team", 09:48:33 INFO - "revision": "fc513f0359ebca43d8b791a458583aeb3bb37f42", 09:48:33 INFO - "stage_platform": "linux64-pgo", 09:48:33 INFO - "builduid": "c23f3af6c7c240c8a853bdacfb8c43bb", 09:48:33 INFO - "slavebuilddir": "test" 09:48:33 INFO - } 09:48:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:48:33 INFO - Running post-action listener: _resource_record_post_action 09:48:33 INFO - [mozharness: 2016-09-28 16:48:33.354311Z] Finished read-buildbot-config step (success) 09:48:33 INFO - [mozharness: 2016-09-28 16:48:33.354400Z] Running download-and-extract step. 09:48:33 INFO - Running pre-action listener: _resource_record_pre_action 09:48:33 INFO - Running main action method: download_and_extract 09:48:33 INFO - mkdir: /builds/slave/test/build/tests 09:48:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:33 INFO - trying https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:48:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:48:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:48:33 INFO - Downloaded 1361 bytes. 09:48:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:48:33 INFO - Using the following test package requirements: 09:48:33 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:33 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:48:33 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:48:33 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'jsshell-linux-x86_64.zip'], 09:48:33 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:48:33 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:48:33 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:48:33 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:48:33 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:48:33 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:48:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:48:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:48:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:48:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:48:34 INFO - Expected file size: 21984091 09:48:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:48:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:48:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:48:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:48:39 INFO - Expected file size: 21984091 09:48:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:48:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:48:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:48:45 INFO - Expected file size: 14414062 09:48: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:45 INFO - trying https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:48:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:48:46 INFO - Downloaded 59760130 bytes. 09:48:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:48:46 INFO - mkdir: /builds/slave/test/properties 09:48:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:46 INFO - Contents: 09:48:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:48:47 INFO - Running post-action listener: _resource_record_post_action 09:48:47 INFO - Running post-action listener: set_extra_try_arguments 09:48:47 INFO - [mozharness: 2016-09-28 16:48:47.284221Z] Finished download-and-extract step (success) 09:48:47 INFO - [mozharness: 2016-09-28 16:48:47.284447Z] Running populate-webroot step. 09:48:47 INFO - Running pre-action listener: _resource_record_pre_action 09:48:47 INFO - Running main action method: populate_webroot 09:48:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:47 INFO - '--tppagecycles', 09:48:47 INFO - '7'], 09:48:47 INFO - 'tests': ['tsvgm']}}, 09:48:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['damp', 'tps']}, 09:48:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'tests': ['damp', 'tps']}, 09:48:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['dromaeo_dom']}, 09:48:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['basic_compositor_video']}, 09:48:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['a11yr', 09:48:47 INFO - 'ts_paint', 09:48:47 INFO - 'tpaint', 09:48:47 INFO - 'sessionrestore', 09:48:47 INFO - 'sessionrestore_no_auto_restore', 09:48:47 INFO - 'tabpaint']}, 09:48:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['tsvgx', 09:48:47 INFO - 'tsvgr_opacity', 09:48:47 INFO - 'tart', 09:48:47 INFO - 'tscrollx', 09:48:47 INFO - 'cart']}, 09:48:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:47 INFO - 'tsvgr_opacity', 09:48:47 INFO - 'tart', 09:48:47 INFO - 'tscrollx', 09:48:47 INFO - 'cart']}, 09:48:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'talos_options': ['--disable-e10s'], 09:48:47 INFO - 'tests': ['tp5o']}, 09:48:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'tests': ['tp5o']}, 09:48:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'talos_options': ['--disable-e10s', 09:48:47 INFO - '--xperf_path', 09:48:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:47 INFO - 'tests': ['tp5n']}, 09:48:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:47 INFO - 'talos_options': ['--xperf_path', 09:48:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:47 INFO - 'tests': ['tp5n']}}, 09:48:47 INFO - 'talos.zip': {'path': '', 09:48:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:47 INFO - Running post-action listener: _resource_record_post_action 09:48:47 INFO - [mozharness: 2016-09-28 16:48:47.299674Z] Finished populate-webroot step (success) 09:48:47 INFO - [mozharness: 2016-09-28 16:48:47.299763Z] Running create-virtualenv step. 09:48:47 INFO - Running pre-action listener: _resource_record_pre_action 09:48:47 INFO - Running main action method: create_virtualenv 09:48:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:47 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 09:48:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:47 INFO - Using real prefix '/usr' 09:48:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:47 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:49 INFO - Installing pip.................done. 09:48:49 INFO - Return code: 0 09:48:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:50 INFO - Reading from file tmpfile_stdout 09:48:50 INFO - Output received: 09:48:50 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:48:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:48:50 INFO - Using env: {'DISPLAY': ':0', 09:48:50 INFO - 'HOME': '/home/cltbld', 09:48:50 INFO - 'LANG': 'en_US.UTF-8', 09:48:50 INFO - 'LANGUAGE': 'en_US:en', 09:48:50 INFO - 'LOGNAME': 'cltbld', 09:48:50 INFO - 'MAIL': '/var/mail/cltbld', 09:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:50 INFO - 'MOZ_NO_REMOTE': '1', 09:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:50 INFO - 'NO_EM_RESTART': '1', 09:48:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:50 INFO - 'PWD': '/builds/slave/test', 09:48:50 INFO - 'SHELL': '/bin/bash', 09:48:50 INFO - 'SHLVL': '1', 09:48:50 INFO - 'TERM': 'linux', 09:48:50 INFO - 'TMOUT': '86400', 09:48:50 INFO - 'USER': 'cltbld', 09:48:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:50 INFO - '_': '/tools/buildbot/bin/python'} 09:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:50 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 09:48:50 INFO - Cleaning up... 09:48:50 INFO - Return code: 0 09:48:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 09:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 09:48:50 INFO - Using env: {'DISPLAY': ':0', 09:48:50 INFO - 'HOME': '/home/cltbld', 09:48:50 INFO - 'LANG': 'en_US.UTF-8', 09:48:50 INFO - 'LANGUAGE': 'en_US:en', 09:48:50 INFO - 'LOGNAME': 'cltbld', 09:48:50 INFO - 'MAIL': '/var/mail/cltbld', 09:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:50 INFO - 'MOZ_NO_REMOTE': '1', 09:48:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:50 INFO - 'NO_EM_RESTART': '1', 09:48:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:50 INFO - 'PWD': '/builds/slave/test', 09:48:50 INFO - 'SHELL': '/bin/bash', 09:48:50 INFO - 'SHLVL': '1', 09:48:50 INFO - 'TERM': 'linux', 09:48:50 INFO - 'TMOUT': '86400', 09:48:50 INFO - 'USER': 'cltbld', 09:48:50 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:50 INFO - '_': '/tools/buildbot/bin/python'} 09:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:50 INFO - Downloading/unpacking psutil>=3.1.1 09:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:52 INFO - Installing collected packages: psutil 09:48:52 INFO - Running setup.py install for psutil 09:48:52 INFO - building 'psutil._psutil_linux' extension 09:48:52 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 09:48:52 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 09:48:52 INFO - building 'psutil._psutil_posix' extension 09:48:52 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 09:48:52 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 09:48:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:53 INFO - Successfully installed psutil 09:48:53 INFO - Cleaning up... 09:48:53 INFO - Return code: 0 09:48:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 09:48:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 09:48:53 INFO - Using env: {'DISPLAY': ':0', 09:48:53 INFO - 'HOME': '/home/cltbld', 09:48:53 INFO - 'LANG': 'en_US.UTF-8', 09:48:53 INFO - 'LANGUAGE': 'en_US:en', 09:48:53 INFO - 'LOGNAME': 'cltbld', 09:48:53 INFO - 'MAIL': '/var/mail/cltbld', 09:48:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:53 INFO - 'MOZ_NO_REMOTE': '1', 09:48:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:53 INFO - 'NO_EM_RESTART': '1', 09:48:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:53 INFO - 'PWD': '/builds/slave/test', 09:48:53 INFO - 'SHELL': '/bin/bash', 09:48:53 INFO - 'SHLVL': '1', 09:48:53 INFO - 'TERM': 'linux', 09:48:53 INFO - 'TMOUT': '86400', 09:48:53 INFO - 'USER': 'cltbld', 09:48:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:53 INFO - '_': '/tools/buildbot/bin/python'} 09:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:55 INFO - Installing collected packages: mozsystemmonitor 09:48:55 INFO - Running setup.py install for mozsystemmonitor 09:48:55 INFO - Successfully installed mozsystemmonitor 09:48:55 INFO - Cleaning up... 09:48:55 INFO - Return code: 0 09:48:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1'] in /builds/slave/test/build 09:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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==2.5.1 09:48:55 INFO - Using env: {'DISPLAY': ':0', 09:48:55 INFO - 'HOME': '/home/cltbld', 09:48:55 INFO - 'LANG': 'en_US.UTF-8', 09:48:55 INFO - 'LANGUAGE': 'en_US:en', 09:48:55 INFO - 'LOGNAME': 'cltbld', 09:48:55 INFO - 'MAIL': '/var/mail/cltbld', 09:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:55 INFO - 'MOZ_NO_REMOTE': '1', 09:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:55 INFO - 'NO_EM_RESTART': '1', 09:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:55 INFO - 'PWD': '/builds/slave/test', 09:48:55 INFO - 'SHELL': '/bin/bash', 09:48:55 INFO - 'SHLVL': '1', 09:48:55 INFO - 'TERM': 'linux', 09:48:55 INFO - 'TMOUT': '86400', 09:48:55 INFO - 'USER': 'cltbld', 09:48:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:55 INFO - '_': '/tools/buildbot/bin/python'} 09:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48:57 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 09:48:57 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 09:48:57 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 09:48:57 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 09:48:57 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 09:48:57 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 09:48:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:57 INFO - warning: no files found matching '*.txt' 09:48:57 INFO - no previously-included directories found matching 'build' 09:48:57 INFO - no previously-included directories found matching 'dist' 09:48:57 INFO - no previously-included directories found matching '.git*' 09:48:57 INFO - Installing collected packages: jsonschema, functools32 09:48:57 INFO - Running setup.py install for functools32 09:48:57 INFO - warning: no files found matching '*.txt' 09:48:57 INFO - no previously-included directories found matching 'build' 09:48:57 INFO - no previously-included directories found matching 'dist' 09:48:57 INFO - no previously-included directories found matching '.git*' 09:48:57 INFO - Successfully installed jsonschema functools32 09:48:57 INFO - Cleaning up... 09:48:57 INFO - Return code: 0 09:48:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 functools32==3.2.3-2 09:48:57 INFO - Using env: {'DISPLAY': ':0', 09:48:57 INFO - 'HOME': '/home/cltbld', 09:48:57 INFO - 'LANG': 'en_US.UTF-8', 09:48:57 INFO - 'LANGUAGE': 'en_US:en', 09:48:57 INFO - 'LOGNAME': 'cltbld', 09:48:57 INFO - 'MAIL': '/var/mail/cltbld', 09:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:57 INFO - 'MOZ_NO_REMOTE': '1', 09:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:57 INFO - 'NO_EM_RESTART': '1', 09:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:57 INFO - 'PWD': '/builds/slave/test', 09:48:57 INFO - 'SHELL': '/bin/bash', 09:48:57 INFO - 'SHLVL': '1', 09:48:57 INFO - 'TERM': 'linux', 09:48:57 INFO - 'TMOUT': '86400', 09:48:57 INFO - 'USER': 'cltbld', 09:48:57 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:57 INFO - '_': '/tools/buildbot/bin/python'} 09:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:58 INFO - Cleaning up... 09:48:58 INFO - Return code: 0 09:48:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:48:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:48:58 INFO - Using env: {'DISPLAY': ':0', 09:48:58 INFO - 'HOME': '/home/cltbld', 09:48:58 INFO - 'LANG': 'en_US.UTF-8', 09:48:58 INFO - 'LANGUAGE': 'en_US:en', 09:48:58 INFO - 'LOGNAME': 'cltbld', 09:48:58 INFO - 'MAIL': '/var/mail/cltbld', 09:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:58 INFO - 'MOZ_NO_REMOTE': '1', 09:48:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:48:58 INFO - 'NO_EM_RESTART': '1', 09:48:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:58 INFO - 'PWD': '/builds/slave/test', 09:48:58 INFO - 'SHELL': '/bin/bash', 09:48:58 INFO - 'SHLVL': '1', 09:48:58 INFO - 'TERM': 'linux', 09:48:58 INFO - 'TMOUT': '86400', 09:48:58 INFO - 'USER': 'cltbld', 09:48:58 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:58 INFO - '_': '/tools/buildbot/bin/python'} 09:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:49:00 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:01 INFO - Downloading docopt-0.6.1.tar.gz 09:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:01 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:01 INFO - Running setup.py install for blobuploader 09:49:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:01 INFO - Running setup.py install for requests 09:49:01 INFO - Running setup.py install for docopt 09:49:01 INFO - Successfully installed blobuploader requests docopt 09:49:01 INFO - Cleaning up... 09:49:01 INFO - Return code: 0 09:49:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 09:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 09:49:01 INFO - Using env: {'DISPLAY': ':0', 09:49:01 INFO - 'HOME': '/home/cltbld', 09:49:01 INFO - 'LANG': 'en_US.UTF-8', 09:49:01 INFO - 'LANGUAGE': 'en_US:en', 09:49:01 INFO - 'LOGNAME': 'cltbld', 09:49:01 INFO - 'MAIL': '/var/mail/cltbld', 09:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:01 INFO - 'MOZ_NO_REMOTE': '1', 09:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:01 INFO - 'NO_EM_RESTART': '1', 09:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:01 INFO - 'PWD': '/builds/slave/test', 09:49:01 INFO - 'SHELL': '/bin/bash', 09:49:01 INFO - 'SHLVL': '1', 09:49:01 INFO - 'TERM': 'linux', 09:49:01 INFO - 'TMOUT': '86400', 09:49:01 INFO - 'USER': 'cltbld', 09:49:01 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:01 INFO - '_': '/tools/buildbot/bin/python'} 09:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:01 INFO - Running setup.py (path:/tmp/pip-mFhbVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:01 INFO - Running setup.py (path:/tmp/pip-xBbnG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:02 INFO - Running setup.py (path:/tmp/pip-Z1o164-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:02 INFO - Running setup.py (path:/tmp/pip-zzvtqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:02 INFO - Running setup.py (path:/tmp/pip-UPEMxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:02 INFO - Running setup.py (path:/tmp/pip-qOAtKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:02 INFO - Running setup.py (path:/tmp/pip-PYSAqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:02 INFO - Running setup.py (path:/tmp/pip-I2t6OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:02 INFO - Running setup.py (path:/tmp/pip-HoTIIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:02 INFO - Running setup.py (path:/tmp/pip-HTuA94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:02 INFO - Running setup.py (path:/tmp/pip-_IEAlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:02 INFO - Running setup.py (path:/tmp/pip-6NXO1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:02 INFO - Running setup.py (path:/tmp/pip-kAMuoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:02 INFO - Running setup.py (path:/tmp/pip-UsGZ2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:02 INFO - Running setup.py (path:/tmp/pip-4KfiPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:02 INFO - Running setup.py (path:/tmp/pip-X8uHRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:03 INFO - Running setup.py (path:/tmp/pip-FBMMkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:03 INFO - Running setup.py install for manifestparser 09:49:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozcrash 09:49:03 INFO - Running setup.py install for mozdebug 09:49:03 INFO - Running setup.py install for mozdevice 09:49:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozfile 09:49:03 INFO - Running setup.py install for mozhttpd 09:49:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozinfo 09:49:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozInstall 09:49:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:03 INFO - Running setup.py install for mozleak 09:49:04 INFO - Running setup.py install for mozlog 09:49:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for moznetwork 09:49:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozprocess 09:49:04 INFO - Running setup.py install for mozprofile 09:49:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozrunner 09:49:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Running setup.py install for mozscreenshot 09:49:04 INFO - Running setup.py install for moztest 09:49:04 INFO - Running setup.py install for mozversion 09:49:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:04 INFO - Cleaning up... 09:49:04 INFO - Return code: 0 09:49:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:49:04 INFO - Using env: {'DISPLAY': ':0', 09:49:04 INFO - 'HOME': '/home/cltbld', 09:49:04 INFO - 'LANG': 'en_US.UTF-8', 09:49:04 INFO - 'LANGUAGE': 'en_US:en', 09:49:04 INFO - 'LOGNAME': 'cltbld', 09:49:04 INFO - 'MAIL': '/var/mail/cltbld', 09:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:04 INFO - 'MOZ_NO_REMOTE': '1', 09:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:04 INFO - 'NO_EM_RESTART': '1', 09:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:04 INFO - 'PWD': '/builds/slave/test', 09:49:04 INFO - 'SHELL': '/bin/bash', 09:49:04 INFO - 'SHLVL': '1', 09:49:04 INFO - 'TERM': 'linux', 09:49:04 INFO - 'TMOUT': '86400', 09:49:04 INFO - 'USER': 'cltbld', 09:49:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:04 INFO - '_': '/tools/buildbot/bin/python'} 09:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:05 INFO - Running setup.py (path:/tmp/pip-IIpjV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:05 INFO - Running setup.py (path:/tmp/pip-q_zu1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:05 INFO - Running setup.py (path:/tmp/pip-tM5spe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:05 INFO - Running setup.py (path:/tmp/pip-X96BOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:05 INFO - Running setup.py (path:/tmp/pip-QraRLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:05 INFO - Running setup.py (path:/tmp/pip-SMmN3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:05 INFO - Running setup.py (path:/tmp/pip-mxS6PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 INFO - Running setup.py (path:/tmp/pip-pJ71St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:05 INFO - Running setup.py (path:/tmp/pip-sFpPsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Running setup.py (path:/tmp/pip-OBRezJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 INFO - Running setup.py (path:/tmp/pip-_6YwL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:05 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)) 09:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:05 INFO - Running setup.py (path:/tmp/pip-sHEdj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:06 INFO - Running setup.py (path:/tmp/pip-OfHgz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:06 INFO - Running setup.py (path:/tmp/pip-Xw5ChN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:06 INFO - Running setup.py (path:/tmp/pip-qgsq4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:06 INFO - Running setup.py (path:/tmp/pip-O6gak7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:06 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)) 09:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:06 INFO - Running setup.py (path:/tmp/pip-Ambxtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:06 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)) 09:49:06 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:49: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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:49:06 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)) 09:49:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:49:06 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:49:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49:08 INFO - Downloading blessings-1.6.tar.gz 09:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:08 INFO - Installing collected packages: blessings 09:49:08 INFO - Running setup.py install for blessings 09:49:08 INFO - Successfully installed blessings 09:49:08 INFO - Cleaning up... 09:49:08 INFO - Return code: 0 09:49:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:08 INFO - Reading from file tmpfile_stdout 09:49:08 INFO - Current package versions: 09:49:08 INFO - argparse == 1.2.1 09:49:08 INFO - blessings == 1.6 09:49:08 INFO - blobuploader == 1.2.4 09:49:08 INFO - docopt == 0.6.1 09:49:08 INFO - functools32 == 3.2.3-2 09:49:08 INFO - jsonschema == 2.5.1 09:49:08 INFO - manifestparser == 1.1 09:49:08 INFO - mozInstall == 1.12 09:49:08 INFO - mozcrash == 0.17 09:49:08 INFO - mozdebug == 0.1 09:49:08 INFO - mozdevice == 0.48 09:49:08 INFO - mozfile == 1.2 09:49:08 INFO - mozhttpd == 0.7 09:49:08 INFO - mozinfo == 0.9 09:49:08 INFO - mozleak == 0.1 09:49:08 INFO - mozlog == 3.4 09:49:08 INFO - moznetwork == 0.27 09:49:08 INFO - mozprocess == 0.23 09:49:08 INFO - mozprofile == 0.28 09:49:08 INFO - mozrunner == 6.12 09:49:08 INFO - mozscreenshot == 0.1 09:49:08 INFO - mozsystemmonitor == 0.3 09:49:08 INFO - moztest == 0.7 09:49:08 INFO - mozversion == 1.4 09:49:08 INFO - psutil == 3.1.1 09:49:08 INFO - requests == 1.2.3 09:49:08 INFO - wsgiref == 0.1.2 09:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:49:08 INFO - Using env: {'DISPLAY': ':0', 09:49:08 INFO - 'HOME': '/home/cltbld', 09:49:08 INFO - 'LANG': 'en_US.UTF-8', 09:49:08 INFO - 'LANGUAGE': 'en_US:en', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MAIL': '/var/mail/cltbld', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SHLVL': '1', 09:49:08 INFO - 'TERM': 'linux', 09:49:08 INFO - 'TMOUT': '86400', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - '_': '/tools/buildbot/bin/python'} 09:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:49:08 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 2)) 09:49:08 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 3)) 09:49:08 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 4)) 09:49:08 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 5)) 09:49:08 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 6)) 09:49:08 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 7)) 09:49:08 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 8)) 09:49:08 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 9)) 09:49:08 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:49:08 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 4)) 09:49:08 INFO - Cleaning up... 09:49:08 INFO - Return code: 0 09:49:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7f844a568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22077b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, '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': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', '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 09:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:49:08 INFO - Using env: {'DISPLAY': ':0', 09:49:08 INFO - 'HOME': '/home/cltbld', 09:49:08 INFO - 'LANG': 'en_US.UTF-8', 09:49:08 INFO - 'LANGUAGE': 'en_US:en', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MAIL': '/var/mail/cltbld', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SHLVL': '1', 09:49:08 INFO - 'TERM': 'linux', 09:49:08 INFO - 'TMOUT': '86400', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - '_': '/tools/buildbot/bin/python'} 09:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:09 INFO - Cleaning up... 09:49:09 INFO - Return code: 0 09:49:09 INFO - Running post-action listener: _resource_record_post_action 09:49:09 INFO - Running post-action listener: _start_resource_monitoring 09:49:09 INFO - Starting resource monitoring. 09:49:09 INFO - [mozharness: 2016-09-28 16:49:09.251711Z] Finished create-virtualenv step (success) 09:49:09 INFO - [mozharness: 2016-09-28 16:49:09.252220Z] Running install step. 09:49:09 INFO - Running pre-action listener: _resource_record_pre_action 09:49:09 INFO - Running main action method: install 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:09 INFO - Reading from file tmpfile_stdout 09:49:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:09 INFO - Reading from file tmpfile_stdout 09:49:09 INFO - Output received: 09:49:09 INFO - Usage: mozinstall [options] installer 09:49:09 INFO - Options: 09:49:09 INFO - -h, --help show this help message and exit 09:49:09 INFO - -d DEST, --destination=DEST 09:49:09 INFO - Directory to install application into. [default: 09:49:09 INFO - "/builds/slave/test"] 09:49:09 INFO - --app=APP Application being installed. [default: firefox] 09:49:09 INFO - mkdir: /builds/slave/test/build/application 09:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:49:26 INFO - Reading from file tmpfile_stdout 09:49:26 INFO - Output received: 09:49:26 INFO - /builds/slave/test/build/application/firefox/firefox 09:49:26 INFO - Running post-action listener: _resource_record_post_action 09:49:26 INFO - [mozharness: 2016-09-28 16:49:26.567210Z] Finished install step (success) 09:49:26 INFO - [mozharness: 2016-09-28 16:49:26.567509Z] Running run-tests step. 09:49:26 INFO - Running pre-action listener: _resource_record_pre_action 09:49:26 INFO - Running main action method: run_tests 09:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:26 INFO - Python 2.7.3 09:49:26 INFO - Return code: 0 09:49:26 INFO - grabbing minidump binary from tooltool 09:49:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:26 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2296790>, '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 0x2297690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:26 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 09:49:26 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 09:49:26 INFO - Calling ['/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'] with output_timeout 600 09:49:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:26 INFO - Return code: 0 09:49:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:49:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:49:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:49:26 INFO - Using env: {'DISPLAY': ':0', 09:49:26 INFO - 'HOME': '/home/cltbld', 09:49:26 INFO - 'LANG': 'en_US.UTF-8', 09:49:26 INFO - 'LANGUAGE': 'en_US:en', 09:49:26 INFO - 'LOGNAME': 'cltbld', 09:49:26 INFO - 'MAIL': '/var/mail/cltbld', 09:49:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:26 INFO - 'MOZ_NO_REMOTE': '1', 09:49:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:26 INFO - 'NO_EM_RESTART': '1', 09:49:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:26 INFO - 'PWD': '/builds/slave/test', 09:49:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:26 INFO - 'SHELL': '/bin/bash', 09:49:26 INFO - 'SHLVL': '1', 09:49:26 INFO - 'TERM': 'linux', 09:49:26 INFO - 'TMOUT': '86400', 09:49:26 INFO - 'USER': 'cltbld', 09:49:26 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564', 09:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:26 INFO - '_': '/tools/buildbot/bin/python'} 09:49:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:49:26 INFO - mozversion application_buildid: 20160928063004 09:49:26 INFO - mozversion application_changeset: fc513f0359ebca43d8b791a458583aeb3bb37f42 09:49:26 INFO - mozversion application_display_name: Nightly 09:49:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:26 INFO - mozversion application_name: Firefox 09:49:26 INFO - mozversion application_remotingname: firefox 09:49:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:49:26 INFO - mozversion application_vendor: Mozilla 09:49:26 INFO - mozversion application_version: 52.0a1 09:49:26 INFO - mozversion platform_buildid: 20160928063004 09:49:26 INFO - mozversion platform_changeset: fc513f0359ebca43d8b791a458583aeb3bb37f42 09:49:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:49:26 INFO - mozversion platform_version: 52.0a1 09:49:26 INFO - using testdate: 1475081366 09:49:26 INFO - actual date: 1475081366 09:49:26 INFO - starting webserver on 'localhost:58706' 09:49:26 INFO - SUITE-START | Running 2 tests 09:49:26 INFO - TEST-START | dromaeo_css 09:49:26 INFO - operating with platform_type : linux_ 09:49:26 INFO - Initialising browser for dromaeo_css test... 09:49:26 INFO - TEST-INFO | started process 23151 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXS22E/profile http://localhost:58706/getInfo.html) 09:49:31 INFO - PROCESS | 23151 | __metrics Screen width/height:1600/1200 09:49:31 INFO - PROCESS | 23151 | colorDepth:24 09:49:31 INFO - PROCESS | 23151 | Browser inner width/height: 1024/697 09:49:31 INFO - PROCESS | 23151 | __metrics 09:49:31 ERROR - PROCESS | 23151 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 09:49:32 INFO - TEST-INFO | 23151: exit 0 09:49:32 INFO - Browser initialized. 09:49:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:32 INFO - TEST-INFO | started process 23273 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTXS22E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:45 INFO - PROCESS | 23273 | RSS: Main: 185573376 09:49:45 INFO - PROCESS | 23273 | 09:50:34 INFO - PROCESS | 23273 | 1475081434753 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] 09:52:44 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58706/tests/dromaeo/cssquery-ext.html) 09:52:46 INFO - PROCESS | 23273 | RSS: Main: 199913472 09:52:46 INFO - PROCESS | 23273 | 09:55:48 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-ext.html (next: http://localhost:58706/tests/dromaeo/cssquery-jquery.html) 09:55:48 INFO - PROCESS | 23273 | RSS: Main: 197619712 09:55:48 INFO - PROCESS | 23273 | 09:58:50 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58706/tests/dromaeo/cssquery-mootools.html) 09:58:51 INFO - PROCESS | 23273 | RSS: Main: 198791168 09:58:51 INFO - PROCESS | 23273 | 10:01:53 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58706/tests/dromaeo/cssquery-prototype.html) 10:01:53 INFO - PROCESS | 23273 | RSS: Main: 195301376 10:01:53 INFO - PROCESS | 23273 | 10:04:56 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58706/tests/dromaeo/cssquery-yui.html) 10:04:57 INFO - PROCESS | 23273 | RSS: Main: 199274496 10:04:57 INFO - PROCESS | 23273 | 10:07:59 INFO - PROCESS | 23273 | Cycle 1(1): loaded http://localhost:58706/tests/dromaeo/cssquery-yui.html (next: http://localhost:58706/tests/dromaeo/cssquery-dojo.html) 10:08:00 INFO - PROCESS | 23273 | RSS: Main: 198881280 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | __start_tp_report 10:08:00 INFO - PROCESS | 23273 | _x_x_mozilla_page_load 10:08:00 INFO - PROCESS | 23273 | _x_x_mozilla_page_load_details 10:08:00 INFO - PROCESS | 23273 | |i|pagename|runs| 10:08:00 INFO - PROCESS | 23273 | |0;dojo.html;3086.351866108117;3099.9380012399765;3117.547955546446;3129.6557378688353;3131.264152924063;5300.072487314724;5481.232627432158;5489.890202195954;5566.192902029204;5577.191307260445;5346.144616861303;5346.198070289459;5354.946450535494;5411.810586629469;5424.593155513342;4817.349657796188;4911.82808601699;4936.753162341886;4969.031038947407;4992.051510213066;4992.051510213066;5081.974590127044;5092.618053645972;5094;5160.612954028444;4830.806767729287;4853.660243782937;4870.805167793284;4959.975200124012;4973.626977976648;4879.975600122012;4935.5804756595735;4943.90112197754;4991.675541089818;4992.201247800371;4855.63582731293;4903.607711383081;4907.509249075099;4916.164252077156;4981.302617633534;5269.473052694738;5306.946930530684;5342.332208473941;5376.462353764592;5396.082665946798;5723.112917497794;5755.68343741094;5801.274840644919;5838.562107841886;5848.941510584882;5768.3654797972185;5785.82642520725;5858.531317494633;5860.06239001762;5867.471927526501;5380.91928621071;5449.918251226235;5453.23654688344;5455.918161227624;5456.345238571317;5924.674142922181;5931.377205393458;5957.434043765836;5966.49284810796;6045.063015232558;5681.943180568224;5691.203231547586;5698.344690360579;5701.657900525899;5721.170430287585;5570.053090974463;5738.282714660668;5773.39379365169;5783.306003279549;5786.189933409326;5782.855428614318;5796.449337312949;5808.651480911159;5812.622179558315;5836.4747172753805;5819.970900145472;5834.095715164156;5837.9124313135335;5842.1236814477315;5843.795467158629;4396.142752163361;4425.623821975104;4438.245498265303;4498.932516012263;4549.067441174551;3962.0293028208275;3967.8016099194924;4013.1371755072214;4016.3373043448087;4031.657309128698;5423.647462914898;5522.641028333145;5530.640508366943;5535.474129957647;5542.63972841764;5618.971905140448;5651.180578816049;5829.591928565044;5834.358220595731;5836.037053886062;5480.588955828329;5487.368952570506;5512.0905050667;5531.948929703424;5538.418466061086;5035.723035233024;5037.748112594356;5044.8234311798915;5045.672031317952;5073.619478539124;6020.57855950088;6020.729067191987;6031.185789918481;6039.245094363205;6081.75672973085;5593.272874526286;5668.348139963792;5787.710614469344;5852.034414321589;5886.087656413263;6056.485198758155;6064.969675151596;6082.7262773174425;6120.357362476876;6136.417040381157;5583.8883222336135;5643.266375371176;5697.259356283656;5728.484436400744;5815.127730840239;5781.277340332458;5793.8551536211935;5813.680247586423;5831.067029275296;5878.676672783038;5636.408177141341;5643.7742490299925;5655.406182350794;5675.262215911905;5715.771369145187;5469.671819690831;5707;5718.971405142947;5719.685417301922;5722.599418040863;5433.049216387196;5603.467670571289;5635.520980716685;5658.094704847204;5726;5716.228309178207;5744;5744.626599271034;5748.109043098327;5749.022666146681;5617.943820561905;5631.633943793804;5652.7173641318595;5653.971730141488;5654.293213348332 10:08:00 INFO - PROCESS | 23273 | |1;ext.html;575.2636625119846;579.6551052123984;582.2779753106149;588.8086371929124;589.201631788926;42146.78926605367;44143.337849932235;44284;48293.75853120729;48334.75832620832;45031.32453013207;45445;45521.54478455214;45625;45912.08175836481;8887.866681999774;9020.819583608323;9038.096190380942;9053.049429809875;9054.547272636375;8764.956175219115;8836.558172091402;8863.867041994343;8868.645254189856;8898.154675305868;8914.732558023237;8953.283737301;8992.460452372847;9006.32452566057;9033.503157326344;8931.553422328858;8949.418287811304;8970.327225458117;8982.595783189707;9000.27997760181;8769.429987050822;8769.605367758402;8836.248918841906;8837.690680826205;8862.86705699415;41845;42431.786596278704;42455;42656.146877062594;42703.78648106739;3156.3213909009673;3238.449463591195;3253.804771713681;3401.183715908189;3671.4492826076303;28589.85705071482;28696;30815.845920770254;31297.8435107823;32112.518312225337;4188.434561334211;4298.226319262531;4306.870793876189;4308.4399028126145;4404.955950440487;240108;240904;241947.99999999825;242107;242300;34347.313053738784;35443.4683479748;57538.7123064382;57777.42222577804;57839;37556.62443375586;37795.244095117945;37955.24089518249;37958.24083518369;38122.618773812464;12668.429920653409;12758.872411275954;12775.23348599087;12848.357582120856;12852.614421567368;10246.53890574926;10301.845472317922;10327.9483602583;10338.0695737384;10346.482675866177;8516.318694504425;8598.914010859937;8601.39790214679;8656.567171641393;8697.260732837653;3335.182880194368;3348.9665103349143;3380.8309584521166;3387.3733359328603;3399.6260411354733;949.2121539122512;987.027777639025;1000.5497526113278;1021.0606242257142;1032.5456799008412;31894.202644933597;32110;32269.677303227138;32314.35371292561;32398.19004524906;957.1337939165066;1044.4568824211367;1049.9920076726276;1066.1364294921138;1147.4090843215752;15192.46826361072;16251;16307.266173022004;16674.33302667904;16739.665206695798;574868.256587169;620171;631644;632892;948514;58758;59701.70149149226;59741;59834.401655983755;60235;1279.8848103670528;1297.1244410023196;1299.246437066499;1310.7509573181064;1324.569514907659;30711.846440768546;33235.33529329448;33240;33457.49813752844;33608.1350644679;52166.47833521616;52231.477685222664;52301.215481768566;52490;53041.20438193507;369028;369882;370043;370591;370713;28499.572506412005;28800;29185.394803285173;29439.85280073586;29512.163418755423;47105.2934205994;47971;48087;48479.75760121177;49426.50573494219;40299.19401611893;40861.79569102254;40926.386104207864;41194;42337.57662423336;40295;41374.9312534366;41505.58494415017;41510.169796603295;42403;39505.80247098746;40595.7970210147;41223.1755364885;41424.37863431991;41570.79214604029;32664.83667581647;36245.45631815472;36265.27469450543;37129.81435092807;37464.250714985;28006.85996570004;28090.157295281173;28223.858880705466;28232.43535129245;28260.71739282581 10:08:00 INFO - PROCESS | 23273 | |2;jquery.html;390.8319422648262;391.9804009799513;405.07036351573134;408.0716370257664;422.51832910482057;19017.239310427565;19509;19892.60214795705;19933.10301036455;20031.499212519695;19056.325714999377;19374.612507749873;19406.70889936652;19569.902150489226;19704.70442943357;5718;5727.742251598667;5860.912086318709;5891.705414729267;5930.525557955353;5770.2210201622665;5873.500752436048;5874.6181498202695;5874.970625146868;5894.58737888348;5617.269754931853;5809.6804675742815;5837.1536127261525;5837.824865254049;5856.677882716449;5583.916241256385;5790.365383750425;5821.27234095738;5847.590668653196;5848.122781582775;5165.380154381461;5171;5176.896462070775;5179.741012949337;5187.714675692835;23217.981662383783;23228;23318.3004509865;23492.177773777836;23778.286651400485;2300.608896487601;2312.7224733032144;2356.7643235676464;2523.205190365029;2665.4802313548867;14453.421863125463;14678.266086695623;15047.172405517693;15328.15695136767;15448.15035173065;1866.9813301866943;1881.4637828218893;1888.33908132154;1962.3426152239017;1969.9606007879763;77481;80754.59622701848;80971;81739;82008.58995705043;10352.378857268588;10472.633457828939;10769.730756731144;10908.454577271104;11000.449977501254;15088.17015064182;15107.093574385572;15133.319000644997;15185.772213416807;15227.923860380628;7060.894086588705;7198.640067996579;7311;7465.178830328659;7503.62481875904;6570.211574611078;6627.966860165765;6640.103586015923;6787.287334829871;6802.557833740791;6284.591501552384;6549.738010479623;6638.037484564711;6712.295209003082;6751.291114433012;1899.4396652987125;1922.5097600111883;1931.314383393903;1981.9009049547465;1994.3717728915635;802.4823988527368;917.9403338782957;927.9257659387367;935.2985261054209;1032.4373216596973;18601.81398186028;20231.393058208276;20279.29022484206;21136.78863211379;21396.679049814265;953.2469349214182;955.2167222877313;984.3847595252967;1011.1809434358047;1020.1175982774846;10839.891601084046;11153.38656373891;11266.887331126747;11342.659720208407;11342.943285283522;503193;507093;511134;515033;516986;58716;74565;95315.52342238244;96260.51869740606;99364.50317748365;986;1017.7201269650906;1042.6038105520006;1043.3844032021143;1048.4705223861902;16835.15824208809;17208.913955430642;17832.91083544574;18053.81946180574;18081.186346614697;38982.805085975524;42015.7899210502;42958.78520607502;43018;44518.777406112764;57174.42825571857;58713.41286587079;60861.391386085576;61687.38312616996;62861;5589.80435684749;5967.671778052249;8255.504669719836;8535.189157030072;8554.828903422022;11352.886471135183;12556.121071524905;12594.370281486072;13315.800262996256;13837.930810345884;10823.994595453847;11118.888111188817;11132.387718675227;11200.887991119984;11225.270357426742;10953.616623418142;11117.11063114965;11357.148213884157;11560.884391155982;11688.43502731675;11102.500387482743;11462.025727812896;11489.655310340702;11495.792941740996;11696.824547631622;8220.383471239562;8532.445391049561;8598.699045533376;8676.78308042304;8734.126587341216;8677.392582519164;10496.370217786956;10980.615678451215;11063.83404248953;11469.942650286695 10:08:00 INFO - PROCESS | 23273 | |3;mootools.html;1462.0569732522524;1484.0353770049467;1586.8016497937758;1710.6749717553655;1728.9394871179504;7862.253085956825;8125.3093487053675;8560.700375486851;9215.769605759859;9219.815603687923;8565;8895.510746908918;8911.064338244489;9188.310876684242;9190.724278271662;2317.374308936586;2323.024329781496;2334.3113781434517;2338.3920180752957;2344.824138189643;2309.364924645719;2336.3458231695067;2355.093289083698;2365.314058922919;2379.9762002380016;2319.3880253224015;2342.1334106380664;2342.390978345625;2343.660169275454;2372.9881350593223;2258.153192552793;2277.191596983072;2277.658351247318;2279.8518096323687;2317.281642690771;2231.6429371300515;2236.966445503319;2244.921427750021;2247.8426510144295;2259.9435014124615;8913.197812196868;8914.598843052077;8953.104689530996;9095.81808363829;9182.770430739218;5008.098542262392;5145.459726728677;5194.532492075731;5200.843974680765;5204;3370.3933292007428;3411.300683359905;3529.2412131391875;3555.7333200010103;3558.7153027757718;1081.7644050295805;1126.7774464705803;1129.8192289233684;1132.3828513460185;1134.1890548257952;12513.499460021589;12568.183068100545;12575.748485030248;12616.116871818977;12628.242305461794;4584.908301834012;4678.532146785295;4702.853422267725;4801.807927682923;4951.529604687549;10518.737031574272;10566.577336906437;10600.840987385196;10617.044465998097;10660.786784264272;5236;5238.528532432099;5242.423333433319;5272.340957380327;5284.603654725834;4193.18232944579;4313.245182093121;4669.95330046702;4777.307290442866;4846.5153484651255;3968.9603103969166;4032.899177520527;4120.670346372283;4130.586941305845;4139.172165566899;1304.3673818198167;1304.7520971015476;1320.8943284537213;1321.0422443728412;1328.5150919914163;793.5318162284279;862.2541501601195;920.4293338130402;924.7364501117149;933.7945651956559;6131.96934015327;6229.53278504114;6310.652914089769;6364.459020983269;6474.223093228748;888.29824438694;909.6816114360054;928.3037721708719;930.1628534319167;942.1614762861059;4004.619561141687;4127.422160897477;4168.583141685807;4241.681873859473;4272.401863739079;11208;11471.770564588662;11488.253263537843;11493.195476316745;11494.540218391337;10817.242793004414;11268.38023908693;11325.490352933974;11438.428078596036;11439.714007149887;8154;8505.74482765518;8512.233898949;8525.275351595059;8529.701460448978;5938.970305148447;5974.1636170935235;6222.191115155001;6266.561340706289;6399.2640846303275;10095;10312.48437578133;10336.12142967826;10339.63811266602;10591.682249532527;10793.974572415607;10843.783124337311;10921.126309895348;10951.123910087334;10977.066949309397;7852.528848269122;7949.562774047483;8052.677892884219;8095.150009248944;8193.221643943816;9042.864357034781;9113.863292050492;9114.225290850087;9117.635294588143;9133;7874.6850125994315;7889.605519724106;7910;8077.717279895175;8230.670773169006;7790.07830822436;7805.531668099932;7854.960725196337;7875.527468351917;7910.604469776603;7152.892706609301;7368.263173682589;7440.851182976418;7470.514416562906;7470.514416562906;5194.3766747990485;5273.947260527346;5285;5321.787128514816;5475.315585551806;5070.442251352422;5134.871628209325;5144.76848541824;5159.871003224799;5176.171812510131 10:08:00 INFO - PROCESS | 23273 | |4;prototype.html;329.2098962490024;333.70133730311375;342.79261047066524;344.51767525464345;351.9102628829648;5416.972915135419;5521.723913804309;5566.55467562595;5578.6611213308815;5582.916256256154;5424.620276580641;5479.6164268501225;5485.890282194364;5492.423295553969;5508.476694713996;4294.570542945696;4346.478422589294;4350.912271932017;4353.434053573031;4354.86773438905;4246.150769846027;4253.617174454299;4289.785510724467;4305.892352691192;4345.804438800262;4256.361545768144;4263.829446822124;4265;4270.5515920828175;4277.721948073381;4241.745495270278;4270.338097594878;4296.140771845612;4308.439902812645;4322.459692538433;3797.430385442206;3798.3163030654473;3799.25914446683;3801.3917773156163;3808.7905165215902;5106.821261255838;5332.160404660386;5344.893102137974;5363.490468405495;5371.274877891513;4826.348442960226;4840.515948405131;4865.41615006198;4867.3915760529935;4893.7063776173545;5428.4028756836715;5695.5443564514735;5722.856928576777;5731.681713205944;5731.742071606787;4028.315186418316;4040.6969477289026;4040.838366465338;4057.7362471439556;4077.673786097105;5915.378885217034;5999.220101386835;6029.125776762345;6044.7582096716515;6055.666938318391;4008.2785098681934;4227.767472788964;4234.322508398642;4235.618794308527;4238.427812245369;4235.639970602471;4238.703290769617;4239.851605193803;4245.341971994346;4254.234237837219;4836.153673107192;4839.516048395203;4854.975725121351;4869.63477739171;4874.731889746027;5468.699221542774;5747.626404283708;5755.453231942959;5763;5764.106563482667;1450.4343306110625;1473.9410423582935;1478.290420598119;1478.8151481064867;1485.2128371962876;902.3322741171486;937.1799675284126;944.8755980383322;949.7226230719671;955.4171955107379;4859.611231101503;4905.362302900671;4907.006725897199;4916.754162291871;4916.803327866917;5127.307988363831;5129.794808207705;5161.561267292247;5193.532582067632;5203.684360459559;4750.429948406213;4912.926306105412;4938.308636790852;4971.602271818246;5024.472430394829;2737.7262273772462;2743.7942154338502;2757.4493593426;2762.6270453488924;2780.470741092391;5271.257407240825;5288.9735551322765;5330.653507521999;5345.251664766935;5350.384705758887;4876.414830220326;5204.866004937957;5230.790768369298;5232.3459567554055;5245.639462242007;5284.682919024871;5348.5186333230195;5356.268303165413;5408.37803652585;5422.566194704335;5153.613478989166;5163.721037141518;5171.198464238049;5171.844844654516;5177;5144.948550514447;5491.258680078138;5817.650940943557;5830.82507524775;5863.921441243381;6152.323244443018;6165.32181460048;6227.443139215196;6230.563860529719;6265.467435268053;3977.090996321724;4081.918291652774;4108.958910410857;4198.223328684203;4217.472815898012;5851.912221316598;5852.502537284378;5870.823875283578;5916.1068458093;5927.407259274038;5710.886377156415;5828.970855145697;5836.883262334814;5850.151727999501;5856.3558008618165;5709.914351284651;5745.8563535911935;5764.452377024176;5775.220345253337;5778.94221057784;5713.800016999385;5728.398518155534;5776.480116789509;5780.10408386684;5796.304443466811;5602.3837377887585;5773;5793.36273009977;5800.709964501673;5870.442307980735;5672.546196304368;5673.971630141988;5681.693210561677;5692.288463942007;5716.342620598518 10:08:00 INFO - PROCESS | 23273 | |5;yui.html;693.44870827692;705.0517054561616;728.3990707666175;739.4564994728873;780.7852840468867;14721.779173312396;15549.689006219869;15564.688706225883;15595.766063509056;15598.532044038682;13905.721885562283;13931.93034034831;13969.79045314321;14021.719565608682;14030;656.4223483334658;671.4997326991374;759.7682706774432;778.2101167315179;786.2766255045372;789.0137328339575;797.4896066517405;797.5932274539969;798.1061211443192;798.6006996501749;850.5704619167312;853.6713365354321;854.1031916487694;854.4830377621543;855.4140413816524;837.3426859914968;843.2242337049929;843.3506200225853;844.907060223378;845.4082142500243;619.4425017484256;620.7206756959387;621.7699451203084;622.9377062293735;623.4700504571124;14443.49447769323;14445.277736113152;14468.131912085308;14544.418223271057;14561.796404790928;14044.508442204473;14140.787888181685;14151.575452736435;14206;14284.785728214085;11574.537018519333;11663.24188927717;11767.2939623622;12038.157328986887;12199.634010979686;3864.28833765125;3932.921341573181;3941.5467221269632;3969.5435024972207;4032.778197199153;78260;81069.18930810675;81521;81650.18349816486;82716;11626.186166968231;11710.531578736754;11809.88190118105;11837.644870653894;11934.22427542207;22991.42521436944;23014.424639383815;23094.191703290304;23199.7680023201;23849.523009539713;7412.888806667905;7826.882596761053;7923.207679232146;7972.3622110231045;8014.879776803353;4504.842330518416;4600;4784.330193772875;4824.614030877521;4870.732109733928;3359.5800524934384;3503.211777350076;3505.316463289634;3505.5968563615;3506.3688536063755;601.197401469035;608.6165715599233;613.5674349583533;626.9310375858652;637.4486069549806;589.3046205477577;671.575612040543;684.4626967830329;698.2214830464097;714.4891402647132;8951.597178127;9244.306676999251;9285.489298088534;9512.47681377517;9816.165625921734;619.3269650005924;657.9440747536418;661;668.5888178770118;709.5246185055921;4492.842750503717;4730.858074257778;4894.480438945521;4982.617617468052;5052.191649336089;115321;124156;125601;126605;130614.34692826476;102586;107474;107988;109147;109541.45229273803;92071;95536.52231738936;96012.9999999986;96823;100168;8430.325573954191;8668.349873759367;8832.470051796912;9025.503597302211;9432.717018489457;49291.26063108985;50855.49144508508;50858.745706271235;50930.745346273034;51521.22718159156;71720.28279717345;75253.62373188099;75699.24300757142;76970.67541022856;78549.60725196337;11743.588974385855;12049.397530123633;12065.396730163633;12101.576444824388;12479.438425271066;14492.492762753252;14752.262386880828;14800.185989770665;14844.332005060014;15288.312025959081;13833.584992450242;14003;14340.641483962567;14886.10683359002;14908.25458727081;13210.537631182862;14056.578302650936;14169.504067357593;14200.21898795576;14619.342129603981;13119.606411807661;13570.796438053634;14122.43510259578;14143.222122783345;14341.139531628136;10074.647387341407;10469.842952355873;10543.472826358498;10600.681979540626;10886.346819190874;9143.77140571491;9163.725088247364;10235.385876847413;10632.787344252916;10954 10:08:00 INFO - PROCESS | 23273 | __end_tp_report 10:08:00 INFO - PROCESS | 23273 | __start_cc_report 10:08:00 INFO - PROCESS | 23273 | _x_x_mozilla_cycle_collect,309 10:08:00 INFO - PROCESS | 23273 | __end_cc_report 10:08:00 INFO - PROCESS | 23273 | __startTimestamp1475082480009__endTimestamp 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | ------- Summary: start ------- 10:08:00 INFO - PROCESS | 23273 | Number of tests: 6 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#0] dojo.html Cycles:165 Average:5399.65 Median:5618.97 stddev:613.10 (10.9%) stddev-sans-first:587.51 10:08:00 INFO - PROCESS | 23273 | Values: 3086.4 3099.9 3117.5 3129.7 3131.3 5300.1 5481.2 5489.9 5566.2 5577.2 5346.1 5346.2 5354.9 5411.8 5424.6 4817.3 4911.8 4936.8 4969.0 4992.1 4992.1 5082.0 5092.6 5094.0 5160.6 4830.8 4853.7 4870.8 4960.0 4973.6 4880.0 4935.6 4943.9 4991.7 4992.2 4855.6 4903.6 4907.5 4916.2 4981.3 5269.5 5306.9 5342.3 5376.5 5396.1 5723.1 5755.7 5801.3 5838.6 5848.9 5768.4 5785.8 5858.5 5860.1 5867.5 5380.9 5449.9 5453.2 5455.9 5456.3 5924.7 5931.4 5957.4 5966.5 6045.1 5681.9 5691.2 5698.3 5701.7 5721.2 5570.1 5738.3 5773.4 5783.3 5786.2 5782.9 5796.4 5808.7 5812.6 5836.5 5820.0 5834.1 5837.9 5842.1 5843.8 4396.1 4425.6 4438.2 4498.9 4549.1 3962.0 3967.8 4013.1 4016.3 4031.7 5423.6 5522.6 5530.6 5535.5 5542.6 5619.0 5651.2 5829.6 5834.4 5836.0 5480.6 5487.4 5512.1 5531.9 5538.4 5035.7 5037.7 5044.8 5045.7 5073.6 6020.6 6020.7 6031.2 6039.2 6081.8 5593.3 5668.3 5787.7 5852.0 5886.1 6056.5 6065.0 6082.7 6120.4 6136.4 5583.9 5643.3 5697.3 5728.5 5815.1 5781.3 5793.9 5813.7 5831.1 5878.7 5636.4 5643.8 5655.4 5675.3 5715.8 5469.7 5707.0 5719.0 5719.7 5722.6 5433.0 5603.5 5635.5 5658.1 5726.0 5716.2 5744.0 5744.6 5748.1 5749.0 5617.9 5631.6 5652.7 5654.0 5654.3 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#1] ext.html Cycles:180 Average:58132.25 Median:41208.59 stddev:128054.62 (310.7%) stddev-sans-first:128340.93 10:08:00 INFO - PROCESS | 23273 | Values: 575.3 579.7 582.3 588.8 589.2 42146.8 44143.3 44284.0 48293.8 48334.8 45031.3 45445.0 45521.5 45625.0 45912.1 8887.9 9020.8 9038.1 9053.0 9054.5 8765.0 8836.6 8863.9 8868.6 8898.2 8914.7 8953.3 8992.5 9006.3 9033.5 8931.6 8949.4 8970.3 8982.6 9000.3 8769.4 8769.6 8836.2 8837.7 8862.9 41845.0 42431.8 42455.0 42656.1 42703.8 3156.3 3238.4 3253.8 3401.2 3671.4 28589.9 28696.0 30815.8 31297.8 32112.5 4188.4 4298.2 4306.9 4308.4 4405.0 240108.0 240904.0 241948.0 242107.0 242300.0 34347.3 35443.5 57538.7 57777.4 57839.0 37556.6 37795.2 37955.2 37958.2 38122.6 12668.4 12758.9 12775.2 12848.4 12852.6 10246.5 10301.8 10327.9 10338.1 10346.5 8516.3 8598.9 8601.4 8656.6 8697.3 3335.2 3349.0 3380.8 3387.4 3399.6 949.2 987.0 1000.5 1021.1 1032.5 31894.2 32110.0 32269.7 32314.4 32398.2 957.1 1044.5 1050.0 1066.1 1147.4 15192.5 16251.0 16307.3 16674.3 16739.7 574868.3 620171.0 631644.0 632892.0 948514.0 58758.0 59701.7 59741.0 59834.4 60235.0 1279.9 1297.1 1299.2 1310.8 1324.6 30711.8 33235.3 33240.0 33457.5 33608.1 52166.5 52231.5 52301.2 52490.0 53041.2 369028.0 369882.0 370043.0 370591.0 370713.0 28499.6 28800.0 29185.4 29439.9 29512.2 47105.3 47971.0 48087.0 48479.8 49426.5 40299.2 40861.8 40926.4 41194.0 42337.6 40295.0 41374.9 41505.6 41510.2 42403.0 39505.8 40595.8 41223.2 41424.4 41570.8 32664.8 36245.5 36265.3 37129.8 37464.3 28006.9 28090.2 28223.9 28232.4 28260.7 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#2] jquery.html Cycles:180 Average:29524.35 Median:20824.14 stddev:84008.66 (403.4%) stddev-sans-first:84215.84 10:08:00 INFO - PROCESS | 23273 | Values: 390.8 392.0 405.1 408.1 422.5 19017.2 19509.0 19892.6 19933.1 20031.5 19056.3 19374.6 19406.7 19569.9 19704.7 5718.0 5727.7 5860.9 5891.7 5930.5 5770.2 5873.5 5874.6 5875.0 5894.6 5617.3 5809.7 5837.2 5837.8 5856.7 5583.9 5790.4 5821.3 5847.6 5848.1 5165.4 5171.0 5176.9 5179.7 5187.7 23218.0 23228.0 23318.3 23492.2 23778.3 2300.6 2312.7 2356.8 2523.2 2665.5 14453.4 14678.3 15047.2 15328.2 15448.2 1867.0 1881.5 1888.3 1962.3 1970.0 77481.0 80754.6 80971.0 81739.0 82008.6 10352.4 10472.6 10769.7 10908.5 11000.4 15088.2 15107.1 15133.3 15185.8 15227.9 7060.9 7198.6 7311.0 7465.2 7503.6 6570.2 6628.0 6640.1 6787.3 6802.6 6284.6 6549.7 6638.0 6712.3 6751.3 1899.4 1922.5 1931.3 1981.9 1994.4 802.5 917.9 927.9 935.3 1032.4 18601.8 20231.4 20279.3 21136.8 21396.7 953.2 955.2 984.4 1011.2 1020.1 10839.9 11153.4 11266.9 11342.7 11342.9 503193.0 507093.0 511134.0 515033.0 516986.0 58716.0 74565.0 95315.5 96260.5 99364.5 986.0 1017.7 1042.6 1043.4 1048.5 16835.2 17208.9 17832.9 18053.8 18081.2 38982.8 42015.8 42958.8 43018.0 44518.8 57174.4 58713.4 60861.4 61687.4 62861.0 5589.8 5967.7 8255.5 8535.2 8554.8 11352.9 12556.1 12594.4 13315.8 13837.9 10824.0 11118.9 11132.4 11200.9 11225.3 10953.6 11117.1 11357.1 11560.9 11688.4 11102.5 11462.0 11489.7 11495.8 11696.8 8220.4 8532.4 8598.7 8676.8 8734.1 8677.4 10496.4 10980.6 11063.8 11469.9 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#3] mootools.html Cycles:180 Average:5949.75 Median:4740.08 stddev:3438.79 (72.5%) stddev-sans-first:3431.90 10:08:00 INFO - PROCESS | 23273 | Values: 1462.1 1484.0 1586.8 1710.7 1728.9 7862.3 8125.3 8560.7 9215.8 9219.8 8565.0 8895.5 8911.1 9188.3 9190.7 2317.4 2323.0 2334.3 2338.4 2344.8 2309.4 2336.3 2355.1 2365.3 2380.0 2319.4 2342.1 2342.4 2343.7 2373.0 2258.2 2277.2 2277.7 2279.9 2317.3 2231.6 2237.0 2244.9 2247.8 2259.9 8913.2 8914.6 8953.1 9095.8 9182.8 5008.1 5145.5 5194.5 5200.8 5204.0 3370.4 3411.3 3529.2 3555.7 3558.7 1081.8 1126.8 1129.8 1132.4 1134.2 12513.5 12568.2 12575.7 12616.1 12628.2 4584.9 4678.5 4702.9 4801.8 4951.5 10518.7 10566.6 10600.8 10617.0 10660.8 5236.0 5238.5 5242.4 5272.3 5284.6 4193.2 4313.2 4670.0 4777.3 4846.5 3969.0 4032.9 4120.7 4130.6 4139.2 1304.4 1304.8 1320.9 1321.0 1328.5 793.5 862.3 920.4 924.7 933.8 6132.0 6229.5 6310.7 6364.5 6474.2 888.3 909.7 928.3 930.2 942.2 4004.6 4127.4 4168.6 4241.7 4272.4 11208.0 11471.8 11488.3 11493.2 11494.5 10817.2 11268.4 11325.5 11438.4 11439.7 8154.0 8505.7 8512.2 8525.3 8529.7 5939.0 5974.2 6222.2 6266.6 6399.3 10095.0 10312.5 10336.1 10339.6 10591.7 10794.0 10843.8 10921.1 10951.1 10977.1 7852.5 7949.6 8052.7 8095.2 8193.2 9042.9 9113.9 9114.2 9117.6 9133.0 7874.7 7889.6 7910.0 8077.7 8230.7 7790.1 7805.5 7855.0 7875.5 7910.6 7152.9 7368.3 7440.9 7470.5 7470.5 5194.4 5273.9 5285.0 5321.8 5475.3 5070.4 5134.9 5144.8 5159.9 5176.2 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#4] prototype.html Cycles:180 Average:4694.09 Median:5185.27 stddev:1370.88 (26.4%) stddev-sans-first:1335.00 10:08:00 INFO - PROCESS | 23273 | Values: 329.2 333.7 342.8 344.5 351.9 5417.0 5521.7 5566.6 5578.7 5582.9 5424.6 5479.6 5485.9 5492.4 5508.5 4294.6 4346.5 4350.9 4353.4 4354.9 4246.2 4253.6 4289.8 4305.9 4345.8 4256.4 4263.8 4265.0 4270.6 4277.7 4241.7 4270.3 4296.1 4308.4 4322.5 3797.4 3798.3 3799.3 3801.4 3808.8 5106.8 5332.2 5344.9 5363.5 5371.3 4826.3 4840.5 4865.4 4867.4 4893.7 5428.4 5695.5 5722.9 5731.7 5731.7 4028.3 4040.7 4040.8 4057.7 4077.7 5915.4 5999.2 6029.1 6044.8 6055.7 4008.3 4227.8 4234.3 4235.6 4238.4 4235.6 4238.7 4239.9 4245.3 4254.2 4836.2 4839.5 4855.0 4869.6 4874.7 5468.7 5747.6 5755.5 5763.0 5764.1 1450.4 1473.9 1478.3 1478.8 1485.2 902.3 937.2 944.9 949.7 955.4 4859.6 4905.4 4907.0 4916.8 4916.8 5127.3 5129.8 5161.6 5193.5 5203.7 4750.4 4912.9 4938.3 4971.6 5024.5 2737.7 2743.8 2757.4 2762.6 2780.5 5271.3 5289.0 5330.7 5345.3 5350.4 4876.4 5204.9 5230.8 5232.3 5245.6 5284.7 5348.5 5356.3 5408.4 5422.6 5153.6 5163.7 5171.2 5171.8 5177.0 5144.9 5491.3 5817.7 5830.8 5863.9 6152.3 6165.3 6227.4 6230.6 6265.5 3977.1 4081.9 4109.0 4198.2 4217.5 5851.9 5852.5 5870.8 5916.1 5927.4 5710.9 5829.0 5836.9 5850.2 5856.4 5709.9 5745.9 5764.5 5775.2 5778.9 5713.8 5728.4 5776.5 5780.1 5796.3 5602.4 5773.0 5793.4 5800.7 5870.4 5672.5 5674.0 5681.7 5692.3 5716.3 10:08:00 INFO - PROCESS | 23273 | 10:08:00 INFO - PROCESS | 23273 | [#5] yui.html Cycles:180 Average:21653.84 Median:4552.42 stddev:32331.32 (710.2%) stddev-sans-first:32383.71 10:08:00 INFO - PROCESS | 23273 | Values: 693.4 705.1 728.4 739.5 780.8 14721.8 15549.7 15564.7 15595.8 15598.5 13905.7 13931.9 13969.8 14021.7 14030.0 656.4 671.5 759.8 778.2 786.3 789.0 797.5 797.6 798.1 798.6 850.6 853.7 854.1 854.5 855.4 837.3 843.2 843.4 844.9 845.4 619.4 620.7 621.8 622.9 623.5 14443.5 14445.3 14468.1 14544.4 14561.8 14044.5 14140.8 14151.6 14206.0 14284.8 11574.5 11663.2 11767.3 12038.2 12199.6 3864.3 3932.9 3941.5 3969.5 4032.8 78260.0 81069.2 81521.0 81650.2 82716.0 11626.2 11710.5 11809.9 11837.6 11934.2 22991.4 23014.4 23094.2 23199.8 23849.5 7412.9 7826.9 7923.2 7972.4 8014.9 4504.8 4600.0 4784.3 4824.6 4870.7 3359.6 3503.2 3505.3 3505.6 3506.4 601.2 608.6 613.6 626.9 637.4 589.3 671.6 684.5 698.2 714.5 8951.6 9244.3 9285.5 9512.5 9816.2 619.3 657.9 661.0 668.6 709.5 4492.8 4730.9 4894.5 4982.6 5052.2 115321.0 124156.0 125601.0 126605.0 130614.3 102586.0 107474.0 107988.0 109147.0 109541.5 92071.0 95536.5 96013.0 96823.0 100168.0 8430.3 8668.3 8832.5 9025.5 9432.7 49291.3 50855.5 50858.7 50930.7 51521.2 71720.3 75253.6 75699.2 76970.7 78549.6 11743.6 12049.4 12065.4 12101.6 12479.4 14492.5 14752.3 14800.2 14844.3 15288.3 13833.6 14003.0 14340.6 14886.1 14908.3 13210.5 14056.6 14169.5 14200.2 14619.3 13119.6 13570.8 14122.4 14143.2 14341.1 10074.6 10469.8 10543.5 10600.7 10886.3 9143.8 9163.7 10235.4 10632.8 10954.0 10:08:00 INFO - PROCESS | 23273 | -------- Summary: end -------- 10:08:00 INFO - PROCESS | 23273 | 10:08:01 INFO - TEST-INFO | 23273: exit 0 10:08:01 INFO - TEST-OK | dromaeo_css | took 1114189ms 10:08:01 INFO - TEST-START | kraken 10:08:01 INFO - operating with platform_type : linux_ 10:08:01 INFO - Initialising browser for kraken test... 10:08:01 INFO - TEST-INFO | started process 23630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptPUygH/profile http://localhost:58706/getInfo.html) 10:08:05 INFO - PROCESS | 23630 | __metrics Screen width/height:1600/1200 10:08:05 INFO - PROCESS | 23630 | colorDepth:24 10:08:05 INFO - PROCESS | 23630 | Browser inner width/height: 1024/697 10:08:05 INFO - PROCESS | 23630 | __metrics 10:08:05 ERROR - PROCESS | 23630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 10:08:06 INFO - TEST-INFO | 23630: exit 0 10:08:06 INFO - Browser initialized. 10:08:06 INFO - Running cycle 1/1 for kraken test... 10:08:06 INFO - TEST-INFO | started process 23752 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptPUygH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:18 INFO - PROCESS | 23752 | RSS: Main: 185282560 10:08:18 INFO - PROCESS | 23752 | 10:08:21 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58706/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:22 INFO - PROCESS | 23752 | RSS: Main: 199667712 10:08:22 INFO - PROCESS | 23752 | 10:08:25 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58706/tests/kraken/driver.html?testName=audio-dft) 10:08:25 INFO - PROCESS | 23752 | RSS: Main: 197230592 10:08:25 INFO - PROCESS | 23752 | 10:08:28 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58706/tests/kraken/driver.html?testName=audio-fft) 10:08:29 INFO - PROCESS | 23752 | RSS: Main: 192884736 10:08:29 INFO - PROCESS | 23752 | 10:08:31 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58706/tests/kraken/driver.html?testName=audio-oscillator) 10:08:32 INFO - PROCESS | 23752 | RSS: Main: 193421312 10:08:32 INFO - PROCESS | 23752 | 10:08:34 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58706/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:34 INFO - PROCESS | 23752 | RSS: Main: 194011136 10:08:34 INFO - PROCESS | 23752 | 10:08:37 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58706/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:38 INFO - PROCESS | 23752 | RSS: Main: 193830912 10:08:38 INFO - PROCESS | 23752 | 10:08:41 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58706/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:42 INFO - PROCESS | 23752 | RSS: Main: 191766528 10:08:42 INFO - PROCESS | 23752 | 10:08:45 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58706/tests/kraken/driver.html?testName=json-parse-financial) 10:08:46 INFO - PROCESS | 23752 | RSS: Main: 193376256 10:08:46 INFO - PROCESS | 23752 | 10:08:47 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58706/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:48 INFO - PROCESS | 23752 | RSS: Main: 193810432 10:08:48 INFO - PROCESS | 23752 | 10:08:50 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:50 INFO - PROCESS | 23752 | RSS: Main: 193486848 10:08:50 INFO - PROCESS | 23752 | 10:08:52 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:53 INFO - PROCESS | 23752 | RSS: Main: 193880064 10:08:53 INFO - PROCESS | 23752 | 10:08:56 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:56 INFO - PROCESS | 23752 | RSS: Main: 193523712 10:08:56 INFO - PROCESS | 23752 | 10:08:59 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:00 INFO - PROCESS | 23752 | RSS: Main: 193880064 10:09:00 INFO - PROCESS | 23752 | 10:09:01 INFO - PROCESS | 23752 | Cycle 1(1): loaded http://localhost:58706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58706/tests/kraken/driver.html?testName=ai-astar) 10:09:02 INFO - PROCESS | 23752 | RSS: Main: 195313664 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | __start_tp_report 10:09:02 INFO - PROCESS | 23752 | _x_x_mozilla_page_load 10:09:02 INFO - PROCESS | 23752 | _x_x_mozilla_page_load_details 10:09:02 INFO - PROCESS | 23752 | |i|pagename|runs| 10:09:02 INFO - PROCESS | 23752 | |0;ai-astar;111;113;112;111;113;112;112;112;114;113 10:09:02 INFO - PROCESS | 23752 | |1;audio-beat-detection;154;125;125;155;125;123;124;120;148;119 10:09:02 INFO - PROCESS | 23752 | |2;audio-dft;163;163;165;166;170;164;163;166;165;164 10:09:02 INFO - PROCESS | 23752 | |3;audio-fft;76;78;77;104;76;77;87;76;104;77 10:09:02 INFO - PROCESS | 23752 | |4;audio-oscillator;84;84;116;84;82;83;99;83;83;112 10:09:02 INFO - PROCESS | 23752 | |5;imaging-gaussian-blur;119;117;118;118;117;118;117;119;118;118 10:09:02 INFO - PROCESS | 23752 | |6;imaging-darkroom;150;149;147;148;147;148;149;150;146;147 10:09:02 INFO - PROCESS | 23752 | |7;imaging-desaturate;96;97;96;96;97;95;95;96;95;95 10:09:02 INFO - PROCESS | 23752 | |8;json-parse-financial;56;53;52;54;54;53;55;58;54;53 10:09:02 INFO - PROCESS | 23752 | |9;json-stringify-tinderbox;59;61;61;61;61;60;60;60;60;61 10:09:02 INFO - PROCESS | 23752 | |10;stanford-crypto-aes;79;79;80;80;79;79;80;80;79;80 10:09:02 INFO - PROCESS | 23752 | |11;stanford-crypto-ccm;128;128;127;127;126;130;127;138;156;128 10:09:02 INFO - PROCESS | 23752 | |12;stanford-crypto-pbkdf2;162;168;165;164;163;164;162;163;161;164 10:09:02 INFO - PROCESS | 23752 | |13;stanford-crypto-sha256-iterative;67;67;68;66;65;67;69;66;64;65 10:09:02 INFO - PROCESS | 23752 | __end_tp_report 10:09:02 INFO - PROCESS | 23752 | __start_cc_report 10:09:02 INFO - PROCESS | 23752 | _x_x_mozilla_cycle_collect,762 10:09:02 INFO - PROCESS | 23752 | __end_cc_report 10:09:02 INFO - PROCESS | 23752 | __startTimestamp1475082542034__endTimestamp 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ------- Summary: start ------- 10:09:02 INFO - PROCESS | 23752 | Number of tests: 14 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.50 stddev:0.95 (0.8%) stddev-sans-first:0.88 10:09:02 INFO - PROCESS | 23752 | Values: 111.0 113.0 112.0 111.0 113.0 112.0 112.0 112.0 114.0 113.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:125.00 stddev:14.43 (11.5%) stddev-sans-first:12.87 10:09:02 INFO - PROCESS | 23752 | Values: 154.0 125.0 125.0 155.0 125.0 123.0 124.0 120.0 148.0 119.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#2] audio-dft Cycles:10 Average:164.90 Median:165.00 stddev:2.13 (1.3%) stddev-sans-first:2.15 10:09:02 INFO - PROCESS | 23752 | Values: 163.0 163.0 165.0 166.0 170.0 164.0 163.0 166.0 165.0 164.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#3] audio-fft Cycles:10 Average:83.20 Median:77.00 stddev:11.44 (14.9%) stddev-sans-first:11.83 10:09:02 INFO - PROCESS | 23752 | Values: 76.0 78.0 77.0 104.0 76.0 77.0 87.0 76.0 104.0 77.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:83.50 stddev:13.12 (15.7%) stddev-sans-first:13.67 10:09:02 INFO - PROCESS | 23752 | Values: 84.0 84.0 116.0 84.0 82.0 83.0 99.0 83.0 83.0 112.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 10:09:02 INFO - PROCESS | 23752 | Values: 119.0 117.0 118.0 118.0 117.0 118.0 117.0 119.0 118.0 118.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.50 stddev:1.37 (0.9%) stddev-sans-first:1.27 10:09:02 INFO - PROCESS | 23752 | Values: 150.0 149.0 147.0 148.0 147.0 148.0 149.0 150.0 146.0 147.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 10:09:02 INFO - PROCESS | 23752 | Values: 96.0 97.0 96.0 96.0 97.0 95.0 95.0 96.0 95.0 95.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:1.75 (3.2%) stddev-sans-first:1.73 10:09:02 INFO - PROCESS | 23752 | Values: 56.0 53.0 52.0 54.0 54.0 53.0 55.0 58.0 54.0 53.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:61.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 10:09:02 INFO - PROCESS | 23752 | Values: 59.0 61.0 61.0 61.0 61.0 60.0 60.0 60.0 60.0 61.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.00 stddev:0.53 (0.7%) stddev-sans-first:0.53 10:09:02 INFO - PROCESS | 23752 | Values: 79.0 79.0 80.0 80.0 79.0 79.0 80.0 80.0 79.0 80.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:128.00 stddev:9.26 (7.2%) stddev-sans-first:9.74 10:09:02 INFO - PROCESS | 23752 | Values: 128.0 128.0 127.0 127.0 126.0 130.0 127.0 138.0 156.0 128.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:164.00 stddev:1.96 (1.2%) stddev-sans-first:1.99 10:09:02 INFO - PROCESS | 23752 | Values: 162.0 168.0 165.0 164.0 163.0 164.0 162.0 163.0 161.0 164.0 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.51 (2.2%) stddev-sans-first:1.58 10:09:02 INFO - PROCESS | 23752 | Values: 67.0 67.0 68.0 66.0 65.0 67.0 69.0 66.0 64.0 65.0 10:09:02 INFO - PROCESS | 23752 | -------- Summary: end -------- 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | 10:09:02 INFO - PROCESS | 23752 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x2000B,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv 10:09:02 INFO - PROCESS | 23752 | 10:09:03 INFO - TEST-INFO | 23752: exit 0 10:09:03 INFO - TEST-OK | kraken | took 62097ms 10:09:03 INFO - SUITE-END | took 1176s 10:09:03 INFO - Completed test suite (00:19:36) 10:09:03 INFO - javascript benchmark 10:09:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7189.726708028696, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3086.351866108117, 3099.9380012399765, 3117.547955546446, 3129.6557378688353, 3131.264152924063, 5300.072487314724, 5481.232627432158, 5489.890202195954, 5566.192902029204, 5577.191307260445, 5346.144616861303, 5346.198070289459, 5354.946450535494, 5411.810586629469, 5424.593155513342, 4817.349657796188, 4911.82808601699, 4936.753162341886, 4969.031038947407, 4992.051510213066, 4992.051510213066, 5081.974590127044, 5092.618053645972, 5094.0, 5160.612954028444, 4830.806767729287, 4853.660243782937, 4870.805167793284, 4959.975200124012, 4973.626977976648, 4879.975600122012, 4935.5804756595735, 4943.90112197754, 4991.675541089818, 4992.201247800371, 4855.63582731293, 4903.607711383081, 4907.509249075099, 4916.164252077156, 4981.302617633534, 5269.473052694738, 5306.946930530684, 5342.332208473941, 5376.462353764592, 5396.082665946798, 5723.112917497794, 5755.68343741094, 5801.274840644919, 5838.562107841886, 5848.941510584882, 5768.3654797972185, 5785.82642520725, 5858.531317494633, 5860.06239001762, 5867.471927526501, 5380.91928621071, 5449.918251226235, 5453.23654688344, 5455.918161227624, 5456.345238571317, 5924.674142922181, 5931.377205393458, 5957.434043765836, 5966.49284810796, 6045.063015232558, 5681.943180568224, 5691.203231547586, 5698.344690360579, 5701.657900525899, 5721.170430287585, 5570.053090974463, 5738.282714660668, 5773.39379365169, 5783.306003279549, 5786.189933409326, 5782.855428614318, 5796.449337312949, 5808.651480911159, 5812.622179558315, 5836.4747172753805, 5819.970900145472, 5834.095715164156, 5837.9124313135335, 5842.1236814477315, 5843.795467158629, 4396.142752163361, 4425.623821975104, 4438.245498265303, 4498.932516012263, 4549.067441174551, 3962.0293028208275, 3967.8016099194924, 4013.1371755072214, 4016.3373043448087, 4031.657309128698, 5423.647462914898, 5522.641028333145, 5530.640508366943, 5535.474129957647, 5542.63972841764, 5618.971905140448, 5651.180578816049, 5829.591928565044, 5834.358220595731, 5836.037053886062, 5480.588955828329, 5487.368952570506, 5512.0905050667, 5531.948929703424, 5538.418466061086, 5035.723035233024, 5037.748112594356, 5044.8234311798915, 5045.672031317952, 5073.619478539124, 6020.57855950088, 6020.729067191987, 6031.185789918481, 6039.245094363205, 6081.75672973085, 5593.272874526286, 5668.348139963792, 5787.710614469344, 5852.034414321589, 5886.087656413263, 6056.485198758155, 6064.969675151596, 6082.7262773174425, 6120.357362476876, 6136.417040381157, 5583.8883222336135, 5643.266375371176, 5697.259356283656, 5728.484436400744, 5815.127730840239, 5781.277340332458, 5793.8551536211935, 5813.680247586423, 5831.067029275296, 5878.676672783038, 5636.408177141341, 5643.7742490299925, 5655.406182350794, 5675.262215911905, 5715.771369145187, 5469.671819690831, 5707.0, 5718.971405142947, 5719.685417301922, 5722.599418040863, 5433.049216387196, 5603.467670571289, 5635.520980716685, 5658.094704847204, 5726.0, 5716.228309178207, 5744.0, 5744.626599271034, 5748.109043098327, 5749.022666146681, 5617.943820561905, 5631.633943793804, 5652.7173641318595, 5653.971730141488, 5654.293213348332], "value": 5358.100769888418, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.2636625119846, 579.6551052123984, 582.2779753106149, 588.8086371929124, 589.201631788926, 42146.78926605367, 44143.337849932235, 44284.0, 48293.75853120729, 48334.75832620832, 45031.32453013207, 45445.0, 45521.54478455214, 45625.0, 45912.08175836481, 8887.866681999774, 9020.819583608323, 9038.096190380942, 9053.049429809875, 9054.547272636375, 8764.956175219115, 8836.558172091402, 8863.867041994343, 8868.645254189856, 8898.154675305868, 8914.732558023237, 8953.283737301, 8992.460452372847, 9006.32452566057, 9033.503157326344, 8931.553422328858, 8949.418287811304, 8970.327225458117, 8982.595783189707, 9000.27997760181, 8769.429987050822, 8769.605367758402, 8836.248918841906, 8837.690680826205, 8862.86705699415, 41845.0, 42431.786596278704, 42455.0, 42656.146877062594, 42703.78648106739, 3156.3213909009673, 3238.449463591195, 3253.804771713681, 3401.183715908189, 3671.4492826076303, 28589.85705071482, 28696.0, 30815.845920770254, 31297.8435107823, 32112.518312225337, 4188.434561334211, 4298.226319262531, 4306.870793876189, 4308.4399028126145, 4404.955950440487, 240108.0, 240904.0, 241947.99999999825, 242107.0, 242300.0, 34347.313053738784, 35443.4683479748, 57538.7123064382, 57777.42222577804, 57839.0, 37556.62443375586, 37795.244095117945, 37955.24089518249, 37958.24083518369, 38122.618773812464, 12668.429920653409, 12758.872411275954, 12775.23348599087, 12848.357582120856, 12852.614421567368, 10246.53890574926, 10301.845472317922, 10327.9483602583, 10338.0695737384, 10346.482675866177, 8516.318694504425, 8598.914010859937, 8601.39790214679, 8656.567171641393, 8697.260732837653, 3335.182880194368, 3348.9665103349143, 3380.8309584521166, 3387.3733359328603, 3399.6260411354733, 949.2121539122512, 987.027777639025, 1000.5497526113278, 1021.0606242257142, 1032.5456799008412, 31894.202644933597, 32110.0, 32269.677303227138, 32314.35371292561, 32398.19004524906, 957.1337939165066, 1044.4568824211367, 1049.9920076726276, 1066.1364294921138, 1147.4090843215752, 15192.46826361072, 16251.0, 16307.266173022004, 16674.33302667904, 16739.665206695798, 574868.256587169, 620171.0, 631644.0, 632892.0, 948514.0, 58758.0, 59701.70149149226, 59741.0, 59834.401655983755, 60235.0, 1279.8848103670528, 1297.1244410023196, 1299.246437066499, 1310.7509573181064, 1324.569514907659, 30711.846440768546, 33235.33529329448, 33240.0, 33457.49813752844, 33608.1350644679, 52166.47833521616, 52231.477685222664, 52301.215481768566, 52490.0, 53041.20438193507, 369028.0, 369882.0, 370043.0, 370591.0, 370713.0, 28499.572506412005, 28800.0, 29185.394803285173, 29439.85280073586, 29512.163418755423, 47105.2934205994, 47971.0, 48087.0, 48479.75760121177, 49426.50573494219, 40299.19401611893, 40861.79569102254, 40926.386104207864, 41194.0, 42337.57662423336, 40295.0, 41374.9312534366, 41505.58494415017, 41510.169796603295, 42403.0, 39505.80247098746, 40595.7970210147, 41223.1755364885, 41424.37863431991, 41570.79214604029, 32664.83667581647, 36245.45631815472, 36265.27469450543, 37129.81435092807, 37464.250714985, 28006.85996570004, 28090.157295281173, 28223.858880705466, 28232.43535129245, 28260.71739282581], "value": 18474.493814812726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.8319422648262, 391.9804009799513, 405.07036351573134, 408.0716370257664, 422.51832910482057, 19017.239310427565, 19509.0, 19892.60214795705, 19933.10301036455, 20031.499212519695, 19056.325714999377, 19374.612507749873, 19406.70889936652, 19569.902150489226, 19704.70442943357, 5718.0, 5727.742251598667, 5860.912086318709, 5891.705414729267, 5930.525557955353, 5770.2210201622665, 5873.500752436048, 5874.6181498202695, 5874.970625146868, 5894.58737888348, 5617.269754931853, 5809.6804675742815, 5837.1536127261525, 5837.824865254049, 5856.677882716449, 5583.916241256385, 5790.365383750425, 5821.27234095738, 5847.590668653196, 5848.122781582775, 5165.380154381461, 5171.0, 5176.896462070775, 5179.741012949337, 5187.714675692835, 23217.981662383783, 23228.0, 23318.3004509865, 23492.177773777836, 23778.286651400485, 2300.608896487601, 2312.7224733032144, 2356.7643235676464, 2523.205190365029, 2665.4802313548867, 14453.421863125463, 14678.266086695623, 15047.172405517693, 15328.15695136767, 15448.15035173065, 1866.9813301866943, 1881.4637828218893, 1888.33908132154, 1962.3426152239017, 1969.9606007879763, 77481.0, 80754.59622701848, 80971.0, 81739.0, 82008.58995705043, 10352.378857268588, 10472.633457828939, 10769.730756731144, 10908.454577271104, 11000.449977501254, 15088.17015064182, 15107.093574385572, 15133.319000644997, 15185.772213416807, 15227.923860380628, 7060.894086588705, 7198.640067996579, 7311.0, 7465.178830328659, 7503.62481875904, 6570.211574611078, 6627.966860165765, 6640.103586015923, 6787.287334829871, 6802.557833740791, 6284.591501552384, 6549.738010479623, 6638.037484564711, 6712.295209003082, 6751.291114433012, 1899.4396652987125, 1922.5097600111883, 1931.314383393903, 1981.9009049547465, 1994.3717728915635, 802.4823988527368, 917.9403338782957, 927.9257659387367, 935.2985261054209, 1032.4373216596973, 18601.81398186028, 20231.393058208276, 20279.29022484206, 21136.78863211379, 21396.679049814265, 953.2469349214182, 955.2167222877313, 984.3847595252967, 1011.1809434358047, 1020.1175982774846, 10839.891601084046, 11153.38656373891, 11266.887331126747, 11342.659720208407, 11342.943285283522, 503193.0, 507093.0, 511134.0, 515033.0, 516986.0, 58716.0, 74565.0, 95315.52342238244, 96260.51869740606, 99364.50317748365, 986.0, 1017.7201269650906, 1042.6038105520006, 1043.3844032021143, 1048.4705223861902, 16835.15824208809, 17208.913955430642, 17832.91083544574, 18053.81946180574, 18081.186346614697, 38982.805085975524, 42015.7899210502, 42958.78520607502, 43018.0, 44518.777406112764, 57174.42825571857, 58713.41286587079, 60861.391386085576, 61687.38312616996, 62861.0, 5589.80435684749, 5967.671778052249, 8255.504669719836, 8535.189157030072, 8554.828903422022, 11352.886471135183, 12556.121071524905, 12594.370281486072, 13315.800262996256, 13837.930810345884, 10823.994595453847, 11118.888111188817, 11132.387718675227, 11200.887991119984, 11225.270357426742, 10953.616623418142, 11117.11063114965, 11357.148213884157, 11560.884391155982, 11688.43502731675, 11102.500387482743, 11462.025727812896, 11489.655310340702, 11495.792941740996, 11696.824547631622, 8220.383471239562, 8532.445391049561, 8598.699045533376, 8676.78308042304, 8734.126587341216, 8677.392582519164, 10496.370217786956, 10980.615678451215, 11063.83404248953, 11469.942650286695], "value": 9255.733209004142, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.0569732522524, 1484.0353770049467, 1586.8016497937758, 1710.6749717553655, 1728.9394871179504, 7862.253085956825, 8125.3093487053675, 8560.700375486851, 9215.769605759859, 9219.815603687923, 8565.0, 8895.510746908918, 8911.064338244489, 9188.310876684242, 9190.724278271662, 2317.374308936586, 2323.024329781496, 2334.3113781434517, 2338.3920180752957, 2344.824138189643, 2309.364924645719, 2336.3458231695067, 2355.093289083698, 2365.314058922919, 2379.9762002380016, 2319.3880253224015, 2342.1334106380664, 2342.390978345625, 2343.660169275454, 2372.9881350593223, 2258.153192552793, 2277.191596983072, 2277.658351247318, 2279.8518096323687, 2317.281642690771, 2231.6429371300515, 2236.966445503319, 2244.921427750021, 2247.8426510144295, 2259.9435014124615, 8913.197812196868, 8914.598843052077, 8953.104689530996, 9095.81808363829, 9182.770430739218, 5008.098542262392, 5145.459726728677, 5194.532492075731, 5200.843974680765, 5204.0, 3370.3933292007428, 3411.300683359905, 3529.2412131391875, 3555.7333200010103, 3558.7153027757718, 1081.7644050295805, 1126.7774464705803, 1129.8192289233684, 1132.3828513460185, 1134.1890548257952, 12513.499460021589, 12568.183068100545, 12575.748485030248, 12616.116871818977, 12628.242305461794, 4584.908301834012, 4678.532146785295, 4702.853422267725, 4801.807927682923, 4951.529604687549, 10518.737031574272, 10566.577336906437, 10600.840987385196, 10617.044465998097, 10660.786784264272, 5236.0, 5238.528532432099, 5242.423333433319, 5272.340957380327, 5284.603654725834, 4193.18232944579, 4313.245182093121, 4669.95330046702, 4777.307290442866, 4846.5153484651255, 3968.9603103969166, 4032.899177520527, 4120.670346372283, 4130.586941305845, 4139.172165566899, 1304.3673818198167, 1304.7520971015476, 1320.8943284537213, 1321.0422443728412, 1328.5150919914163, 793.5318162284279, 862.2541501601195, 920.4293338130402, 924.7364501117149, 933.7945651956559, 6131.96934015327, 6229.53278504114, 6310.652914089769, 6364.459020983269, 6474.223093228748, 888.29824438694, 909.6816114360054, 928.3037721708719, 930.1628534319167, 942.1614762861059, 4004.619561141687, 4127.422160897477, 4168.583141685807, 4241.681873859473, 4272.401863739079, 11208.0, 11471.770564588662, 11488.253263537843, 11493.195476316745, 11494.540218391337, 10817.242793004414, 11268.38023908693, 11325.490352933974, 11438.428078596036, 11439.714007149887, 8154.0, 8505.74482765518, 8512.233898949, 8525.275351595059, 8529.701460448978, 5938.970305148447, 5974.1636170935235, 6222.191115155001, 6266.561340706289, 6399.2640846303275, 10095.0, 10312.48437578133, 10336.12142967826, 10339.63811266602, 10591.682249532527, 10793.974572415607, 10843.783124337311, 10921.126309895348, 10951.123910087334, 10977.066949309397, 7852.528848269122, 7949.562774047483, 8052.677892884219, 8095.150009248944, 8193.221643943816, 9042.864357034781, 9113.863292050492, 9114.225290850087, 9117.635294588143, 9133.0, 7874.6850125994315, 7889.605519724106, 7910.0, 8077.717279895175, 8230.670773169006, 7790.07830822436, 7805.531668099932, 7854.960725196337, 7875.527468351917, 7910.604469776603, 7152.892706609301, 7368.263173682589, 7440.851182976418, 7470.514416562906, 7470.514416562906, 5194.3766747990485, 5273.947260527346, 5285.0, 5321.787128514816, 5475.315585551806, 5070.442251352422, 5134.871628209325, 5144.76848541824, 5159.871003224799, 5176.171812510131], "value": 4716.3633751310035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.2098962490024, 333.70133730311375, 342.79261047066524, 344.51767525464345, 351.9102628829648, 5416.972915135419, 5521.723913804309, 5566.55467562595, 5578.6611213308815, 5582.916256256154, 5424.620276580641, 5479.6164268501225, 5485.890282194364, 5492.423295553969, 5508.476694713996, 4294.570542945696, 4346.478422589294, 4350.912271932017, 4353.434053573031, 4354.86773438905, 4246.150769846027, 4253.617174454299, 4289.785510724467, 4305.892352691192, 4345.804438800262, 4256.361545768144, 4263.829446822124, 4265.0, 4270.5515920828175, 4277.721948073381, 4241.745495270278, 4270.338097594878, 4296.140771845612, 4308.439902812645, 4322.459692538433, 3797.430385442206, 3798.3163030654473, 3799.25914446683, 3801.3917773156163, 3808.7905165215902, 5106.821261255838, 5332.160404660386, 5344.893102137974, 5363.490468405495, 5371.274877891513, 4826.348442960226, 4840.515948405131, 4865.41615006198, 4867.3915760529935, 4893.7063776173545, 5428.4028756836715, 5695.5443564514735, 5722.856928576777, 5731.681713205944, 5731.742071606787, 4028.315186418316, 4040.6969477289026, 4040.838366465338, 4057.7362471439556, 4077.673786097105, 5915.378885217034, 5999.220101386835, 6029.125776762345, 6044.7582096716515, 6055.666938318391, 4008.2785098681934, 4227.767472788964, 4234.322508398642, 4235.618794308527, 4238.427812245369, 4235.639970602471, 4238.703290769617, 4239.851605193803, 4245.341971994346, 4254.234237837219, 4836.153673107192, 4839.516048395203, 4854.975725121351, 4869.63477739171, 4874.731889746027, 5468.699221542774, 5747.626404283708, 5755.453231942959, 5763.0, 5764.106563482667, 1450.4343306110625, 1473.9410423582935, 1478.290420598119, 1478.8151481064867, 1485.2128371962876, 902.3322741171486, 937.1799675284126, 944.8755980383322, 949.7226230719671, 955.4171955107379, 4859.611231101503, 4905.362302900671, 4907.006725897199, 4916.754162291871, 4916.803327866917, 5127.307988363831, 5129.794808207705, 5161.561267292247, 5193.532582067632, 5203.684360459559, 4750.429948406213, 4912.926306105412, 4938.308636790852, 4971.602271818246, 5024.472430394829, 2737.7262273772462, 2743.7942154338502, 2757.4493593426, 2762.6270453488924, 2780.470741092391, 5271.257407240825, 5288.9735551322765, 5330.653507521999, 5345.251664766935, 5350.384705758887, 4876.414830220326, 5204.866004937957, 5230.790768369298, 5232.3459567554055, 5245.639462242007, 5284.682919024871, 5348.5186333230195, 5356.268303165413, 5408.37803652585, 5422.566194704335, 5153.613478989166, 5163.721037141518, 5171.198464238049, 5171.844844654516, 5177.0, 5144.948550514447, 5491.258680078138, 5817.650940943557, 5830.82507524775, 5863.921441243381, 6152.323244443018, 6165.32181460048, 6227.443139215196, 6230.563860529719, 6265.467435268053, 3977.090996321724, 4081.918291652774, 4108.958910410857, 4198.223328684203, 4217.472815898012, 5851.912221316598, 5852.502537284378, 5870.823875283578, 5916.1068458093, 5927.407259274038, 5710.886377156415, 5828.970855145697, 5836.883262334814, 5850.151727999501, 5856.3558008618165, 5709.914351284651, 5745.8563535911935, 5764.452377024176, 5775.220345253337, 5778.94221057784, 5713.800016999385, 5728.398518155534, 5776.480116789509, 5780.10408386684, 5796.304443466811, 5602.3837377887585, 5773.0, 5793.36273009977, 5800.709964501673, 5870.442307980735, 5672.546196304368, 5673.971630141988, 5681.693210561677, 5692.288463942007, 5716.342620598518], "value": 4258.842974590556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.44870827692, 705.0517054561616, 728.3990707666175, 739.4564994728873, 780.7852840468867, 14721.779173312396, 15549.689006219869, 15564.688706225883, 15595.766063509056, 15598.532044038682, 13905.721885562283, 13931.93034034831, 13969.79045314321, 14021.719565608682, 14030.0, 656.4223483334658, 671.4997326991374, 759.7682706774432, 778.2101167315179, 786.2766255045372, 789.0137328339575, 797.4896066517405, 797.5932274539969, 798.1061211443192, 798.6006996501749, 850.5704619167312, 853.6713365354321, 854.1031916487694, 854.4830377621543, 855.4140413816524, 837.3426859914968, 843.2242337049929, 843.3506200225853, 844.907060223378, 845.4082142500243, 619.4425017484256, 620.7206756959387, 621.7699451203084, 622.9377062293735, 623.4700504571124, 14443.49447769323, 14445.277736113152, 14468.131912085308, 14544.418223271057, 14561.796404790928, 14044.508442204473, 14140.787888181685, 14151.575452736435, 14206.0, 14284.785728214085, 11574.537018519333, 11663.24188927717, 11767.2939623622, 12038.157328986887, 12199.634010979686, 3864.28833765125, 3932.921341573181, 3941.5467221269632, 3969.5435024972207, 4032.778197199153, 78260.0, 81069.18930810675, 81521.0, 81650.18349816486, 82716.0, 11626.186166968231, 11710.531578736754, 11809.88190118105, 11837.644870653894, 11934.22427542207, 22991.42521436944, 23014.424639383815, 23094.191703290304, 23199.7680023201, 23849.523009539713, 7412.888806667905, 7826.882596761053, 7923.207679232146, 7972.3622110231045, 8014.879776803353, 4504.842330518416, 4600.0, 4784.330193772875, 4824.614030877521, 4870.732109733928, 3359.5800524934384, 3503.211777350076, 3505.316463289634, 3505.5968563615, 3506.3688536063755, 601.197401469035, 608.6165715599233, 613.5674349583533, 626.9310375858652, 637.4486069549806, 589.3046205477577, 671.575612040543, 684.4626967830329, 698.2214830464097, 714.4891402647132, 8951.597178127, 9244.306676999251, 9285.489298088534, 9512.47681377517, 9816.165625921734, 619.3269650005924, 657.9440747536418, 661.0, 668.5888178770118, 709.5246185055921, 4492.842750503717, 4730.858074257778, 4894.480438945521, 4982.617617468052, 5052.191649336089, 115321.0, 124156.0, 125601.0, 126605.0, 130614.34692826476, 102586.0, 107474.0, 107988.0, 109147.0, 109541.45229273803, 92071.0, 95536.52231738936, 96012.9999999986, 96823.0, 100168.0, 8430.325573954191, 8668.349873759367, 8832.470051796912, 9025.503597302211, 9432.717018489457, 49291.26063108985, 50855.49144508508, 50858.745706271235, 50930.745346273034, 51521.22718159156, 71720.28279717345, 75253.62373188099, 75699.24300757142, 76970.67541022856, 78549.60725196337, 11743.588974385855, 12049.397530123633, 12065.396730163633, 12101.576444824388, 12479.438425271066, 14492.492762753252, 14752.262386880828, 14800.185989770665, 14844.332005060014, 15288.312025959081, 13833.584992450242, 14003.0, 14340.641483962567, 14886.10683359002, 14908.25458727081, 13210.537631182862, 14056.578302650936, 14169.504067357593, 14200.21898795576, 14619.342129603981, 13119.606411807661, 13570.796438053634, 14122.43510259578, 14143.222122783345, 14341.139531628136, 10074.647387341407, 10469.842952355873, 10543.472826358498, 10600.681979540626, 10886.346819190874, 9143.77140571491, 9163.725088247364, 10235.385876847413, 10632.787344252916, 10954.0], "value": 7504.818393801332, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1500.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 112.0, 111.0, 113.0, 112.0, 112.0, 112.0, 114.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 125.0, 155.0, 125.0, 123.0, 124.0, 120.0, 148.0, 119.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 166.0, 170.0, 164.0, 163.0, 166.0, 165.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 104.0, 76.0, 77.0, 87.0, 76.0, 104.0, 77.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 84.0, 82.0, 83.0, 99.0, 83.0, 83.0, 112.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 119.0, 118.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 147.0, 148.0, 147.0, 148.0, 149.0, 150.0, 146.0, 147.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 54.0, 54.0, 53.0, 55.0, 58.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 80.0, 79.0, 79.0, 80.0, 80.0, 79.0, 80.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 127.0, 127.0, 126.0, 130.0, 127.0, 138.0, 156.0, 128.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 168.0, 165.0, 164.0, 163.0, 164.0, 162.0, 163.0, 161.0, 164.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 65.0, 67.0, 69.0, 66.0, 64.0, 65.0], "value": 66.4, "unit": "score"}]}]} 10:09:03 INFO - Return code: 0 10:09:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:03 ERROR - # TBPL SUCCESS # 10:09:03 INFO - Running post-action listener: _resource_record_post_action 10:09:03 INFO - [mozharness: 2016-09-28 17:09:03.324997Z] Finished run-tests step (success) 10:09:03 INFO - Running post-run listener: _resource_record_post_run 10:09:03 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393232384; Read time: 76; Write time: 300728 10:09:03 INFO - TinderboxPrint: CPU usage
13.4% 10:09:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 76 10:09:03 INFO - TinderboxPrint: I/O write bytes / time
393,232,384 / 300,728 10:09:03 INFO - TinderboxPrint: CPU idle
8,241.7 (86.5%) 10:09:03 INFO - TinderboxPrint: CPU user
1,219.7 (12.8%) 10:09:03 INFO - TinderboxPrint: Swap in / out
0 / 0 10:09:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103874560; Read time: 0; Write time: 104740 10:09:03 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289357824; Read time: 76; Write time: 195988 10:09:03 INFO - Running post-run listener: _upload_blobber_files 10:09:03 INFO - Blob upload gear active. 10:09:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:09:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:05 INFO - (blobuploader) - INFO - Done attempting. 10:09:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:08 INFO - (blobuploader) - INFO - Done attempting. 10:09:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:09:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:09:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:09 INFO - (blobuploader) - INFO - Done attempting. 10:09:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:09 INFO - Return code: 0 10:09:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/650b247c2a087cc5259bb92c7cb50a8a98858626ef4c829ec9bc84e898f0198b92da5bc235b801cab9a00aa933f4e3aaaacf1aa3341ac737a423739da28f41d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7984585c16a50c9fc74fe26ad8e8772f2af9187fa75292e28a930103b5d3610bd4c771979a94bbf6b56523818e799bf30e78e9641b958b831c17bc0fb175795a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e98458bc2dfd06738553c9688beac76f1b9722ab5ee382026701e7f7b7ceb5449264675dd2ce574c4da02158e0ba1ad36a15e4e64ef6b2096847b3f9cc0256c9"} 10:09:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:09 INFO - Contents: 10:09:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/650b247c2a087cc5259bb92c7cb50a8a98858626ef4c829ec9bc84e898f0198b92da5bc235b801cab9a00aa933f4e3aaaacf1aa3341ac737a423739da28f41d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7984585c16a50c9fc74fe26ad8e8772f2af9187fa75292e28a930103b5d3610bd4c771979a94bbf6b56523818e799bf30e78e9641b958b831c17bc0fb175795a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e98458bc2dfd06738553c9688beac76f1b9722ab5ee382026701e7f7b7ceb5449264675dd2ce574c4da02158e0ba1ad36a15e4e64ef6b2096847b3f9cc0256c9"} 10:09:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:09 INFO - Copying logs to upload dir... 10:09:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.991369 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-28 10:09:07.988391) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 10:09:07.992180) ========= 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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/650b247c2a087cc5259bb92c7cb50a8a98858626ef4c829ec9bc84e898f0198b92da5bc235b801cab9a00aa933f4e3aaaacf1aa3341ac737a423739da28f41d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7984585c16a50c9fc74fe26ad8e8772f2af9187fa75292e28a930103b5d3610bd4c771979a94bbf6b56523818e799bf30e78e9641b958b831c17bc0fb175795a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e98458bc2dfd06738553c9688beac76f1b9722ab5ee382026701e7f7b7ceb5449264675dd2ce574c4da02158e0ba1ad36a15e4e64ef6b2096847b3f9cc0256c9"} build_url:https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/Fr2A_LMARg60waVqGT1BBQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/650b247c2a087cc5259bb92c7cb50a8a98858626ef4c829ec9bc84e898f0198b92da5bc235b801cab9a00aa933f4e3aaaacf1aa3341ac737a423739da28f41d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7984585c16a50c9fc74fe26ad8e8772f2af9187fa75292e28a930103b5d3610bd4c771979a94bbf6b56523818e799bf30e78e9641b958b831c17bc0fb175795a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e98458bc2dfd06738553c9688beac76f1b9722ab5ee382026701e7f7b7ceb5449264675dd2ce574c4da02158e0ba1ad36a15e4e64ef6b2096847b3f9cc0256c9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 10:09:08.021190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 10:09:08.021526) ========= 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=3a3620fb28aea1604ab609ef000001d2-1475081310.643805-533169564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 10:09:08.104288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 10:09:08.104591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 10:09:08.104891) ========= ========= Total master_lag: 0.16 =========