builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-023 starttime: 1474484758.08 results: success (0) buildid: 20160921094107 builduid: bb6c18100c3f437386ec74a0fb648fa6 revision: 26e0cc5ad055a64c8c38ef067a179a4e1b179cac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.082163) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.082630) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.082996) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.101198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.101508) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 12:05:58-- 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% 24.3M=0s 2016-09-21 12:05:58 (24.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074753 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.193851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.194573) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022397 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.245558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:58.245932) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 26e0cc5ad055a64c8c38ef067a179a4e1b179cac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 26e0cc5ad055a64c8c38ef067a179a4e1b179cac --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=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 12:05:58,953 truncating revision to first 12 chars 2016-09-21 12:05:58,953 Setting DEBUG logging. 2016-09-21 12:05:58,953 attempt 1/10 2016-09-21 12:05:58,953 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/26e0cc5ad055?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 12:05:59,300 unpacking tar archive at: autoland-26e0cc5ad055/testing/mozharness/ program finished with exit code 0 elapsedTime=0.784625 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:59.048566) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:59.049305) ========= script_repo_revision: 26e0cc5ad055a64c8c38ef067a179a4e1b179cac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:59.050296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:59.051033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 12:05:59.063305) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-21 12:05:59.064041) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:05:59 INFO - MultiFileLogger online at 20160921 12:05:59 in /builds/slave/test 12:05:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 12:05:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:05:59 INFO - {'append_to_log': False, 12:05:59 INFO - 'base_work_dir': '/builds/slave/test', 12:05:59 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 12:05:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:05:59 INFO - 'branch': 'Autoland-Non-PGO', 12:05:59 INFO - 'buildbot_json_path': 'buildprops.json', 12:05:59 INFO - 'config_files': ('talos/linux_config.py',), 12:05:59 INFO - 'default_actions': ('clobber', 12:05:59 INFO - 'read-buildbot-config', 12:05:59 INFO - 'download-and-extract', 12:05:59 INFO - 'populate-webroot', 12:05:59 INFO - 'create-virtualenv', 12:05:59 INFO - 'install', 12:05:59 INFO - 'run-tests'), 12:05:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:05:59 INFO - 'download_minidump_stackwalk': True, 12:05:59 INFO - 'download_symbols': 'ondemand', 12:05:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:05:59 INFO - 'tooltool.py': '/tools/tooltool.py', 12:05:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:05:59 INFO - '/tools/misc-python/virtualenv.py')}, 12:05:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:05:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:05:59 INFO - 'installer_path': 'installer.exe', 12:05:59 INFO - 'log_level': 'info', 12:05:59 INFO - 'log_name': 'talos', 12:05:59 INFO - 'log_to_console': True, 12:05:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:05:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:05:59 INFO - 'opt_config_files': (), 12:05:59 INFO - 'pip_index': False, 12:05:59 INFO - 'sps_profile': False, 12:05:59 INFO - 'sps_profile_interval': 0, 12:05:59 INFO - 'suite': 'dromaeojs-e10s', 12:05:59 INFO - 'system_bits': '32', 12:05:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:05:59 INFO - 'title': 'talos-linux64-ix-023', 12:05:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:05:59 INFO - 'use_talos_json': True, 12:05:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:05:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:05:59 INFO - 'work_dir': 'build'} 12:05:59 INFO - [mozharness: 2016-09-21 19:05:59.871798Z] Running clobber step. 12:05:59 INFO - Running pre-action listener: _resource_record_pre_action 12:05:59 INFO - Running main action method: clobber 12:05:59 INFO - rmtree: /builds/slave/test/build 12:05:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:00 INFO - Running post-action listener: _resource_record_post_action 12:06:00 INFO - [mozharness: 2016-09-21 19:06:00.853649Z] Finished clobber step (success) 12:06:00 INFO - [mozharness: 2016-09-21 19:06:00.853750Z] Running read-buildbot-config step. 12:06:00 INFO - Running pre-action listener: _resource_record_pre_action 12:06:00 INFO - Running main action method: read_buildbot_config 12:06:00 INFO - Using buildbot properties: 12:06:00 INFO - { 12:06:00 INFO - "project": "", 12:06:00 INFO - "product": "firefox", 12:06:00 INFO - "script_repo_revision": "production", 12:06:00 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 12:06:00 INFO - "repository": "", 12:06:00 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 12:06:00 INFO - "buildid": "20160921094107", 12:06:00 INFO - "pgo_build": "False", 12:06:00 INFO - "basedir": "/builds/slave/test", 12:06:00 INFO - "buildnumber": 477, 12:06:00 INFO - "slavename": "talos-linux64-ix-023", 12:06:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:06:00 INFO - "platform": "ubuntu64_hw", 12:06:00 INFO - "scheduler": "tests-autoland-linux64-talos-8-3600", 12:06:00 INFO - "branch": "autoland", 12:06:00 INFO - "repo_path": "integration/autoland", 12:06:00 INFO - "revision": "26e0cc5ad055a64c8c38ef067a179a4e1b179cac", 12:06:00 INFO - "stage_platform": "linux64", 12:06:00 INFO - "builduid": "bb6c18100c3f437386ec74a0fb648fa6", 12:06:00 INFO - "slavebuilddir": "test" 12:06:00 INFO - } 12:06:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 12:06:00 INFO - Running post-action listener: _resource_record_post_action 12:06:00 INFO - [mozharness: 2016-09-21 19:06:00.856195Z] Finished read-buildbot-config step (success) 12:06:00 INFO - [mozharness: 2016-09-21 19:06:00.856285Z] Running download-and-extract step. 12:06:00 INFO - Running pre-action listener: _resource_record_pre_action 12:06:00 INFO - Running main action method: download_and_extract 12:06:00 INFO - mkdir: /builds/slave/test/build/tests 12:06:00 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')]} 12:06:00 INFO - trying https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 12:06:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/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 12:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/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 12:06:01 INFO - Downloaded 1361 bytes. 12:06:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 12:06:01 INFO - Using the following test package requirements: 12:06:01 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 12:06:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:06:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:06:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'jsshell-linux-x86_64.zip'], 12:06:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:06:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 12:06:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:06:01 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:06:01 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:06:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:01 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:06:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:06:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 12:06:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:06:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:06:02 INFO - Expected file size: 20373143 12:06:06 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 12:06:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 12:06:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:06:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:06:06 INFO - Expected file size: 20373143 12:06:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 12:06:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:06:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:06:12 INFO - Expected file size: 14414059 12:06:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:12 INFO - trying https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 12:06:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/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 12:06:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/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 12:06:13 INFO - Downloaded 55592692 bytes. 12:06:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 12:06:13 INFO - mkdir: /builds/slave/test/properties 12:06:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:06:13 INFO - Writing to file /builds/slave/test/properties/build_url 12:06:13 INFO - Contents: 12:06:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 12:06:14 INFO - Running post-action listener: _resource_record_post_action 12:06:14 INFO - Running post-action listener: set_extra_try_arguments 12:06:14 INFO - [mozharness: 2016-09-21 19:06:14.466723Z] Finished download-and-extract step (success) 12:06:14 INFO - [mozharness: 2016-09-21 19:06:14.466950Z] Running populate-webroot step. 12:06:14 INFO - Running pre-action listener: _resource_record_pre_action 12:06:14 INFO - Running main action method: populate_webroot 12:06:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:06:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:06:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:06:14 INFO - '--tppagecycles', 12:06:14 INFO - '7'], 12:06:14 INFO - 'tests': ['tsvgm']}}, 12:06:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:06:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:06:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:06:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:06:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['damp', 'tps']}, 12:06:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'tests': ['damp', 'tps']}, 12:06:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['dromaeo_dom']}, 12:06:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:06:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['basic_compositor_video']}, 12:06:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:06:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'other-e10s': {'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['a11yr', 12:06:14 INFO - 'ts_paint', 12:06:14 INFO - 'tpaint', 12:06:14 INFO - 'sessionrestore', 12:06:14 INFO - 'sessionrestore_no_auto_restore', 12:06:14 INFO - 'tabpaint']}, 12:06:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['tsvgx', 12:06:14 INFO - 'tsvgr_opacity', 12:06:14 INFO - 'tart', 12:06:14 INFO - 'tscrollx', 12:06:14 INFO - 'cart']}, 12:06:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:06:14 INFO - 'tsvgr_opacity', 12:06:14 INFO - 'tart', 12:06:14 INFO - 'tscrollx', 12:06:14 INFO - 'cart']}, 12:06:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'talos_options': ['--disable-e10s'], 12:06:14 INFO - 'tests': ['tp5o']}, 12:06:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'tests': ['tp5o']}, 12:06:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'talos_options': ['--disable-e10s', 12:06:14 INFO - '--xperf_path', 12:06:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:14 INFO - 'tests': ['tp5n']}, 12:06:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:14 INFO - 'talos_options': ['--xperf_path', 12:06:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:14 INFO - 'tests': ['tp5n']}}, 12:06:14 INFO - 'talos.zip': {'path': '', 12:06:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:06:14 INFO - Running post-action listener: _resource_record_post_action 12:06:14 INFO - [mozharness: 2016-09-21 19:06:14.483613Z] Finished populate-webroot step (success) 12:06:14 INFO - [mozharness: 2016-09-21 19:06:14.483700Z] Running create-virtualenv step. 12:06:14 INFO - Running pre-action listener: _resource_record_pre_action 12:06:14 INFO - Running main action method: create_virtualenv 12:06:14 INFO - Creating virtualenv /builds/slave/test/build/venv 12:06:14 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 12:06:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:06:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:06:14 INFO - Using real prefix '/usr' 12:06:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:06:15 INFO - Installing distribute.............................................................................................................................................................................................done. 12:06:17 INFO - Installing pip.................done. 12:06:17 INFO - Return code: 0 12:06:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:06:17 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')]} 12:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:17 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')]} 12:06:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:17 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:17 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 12:06:17 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 12:06:17 INFO - Using env: {'DISPLAY': ':0', 12:06:17 INFO - 'HOME': '/home/cltbld', 12:06:17 INFO - 'LANG': 'en_US.UTF-8', 12:06:17 INFO - 'LANGUAGE': 'en_US:en', 12:06:17 INFO - 'LOGNAME': 'cltbld', 12:06:17 INFO - 'MAIL': '/var/mail/cltbld', 12:06:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:17 INFO - 'MOZ_NO_REMOTE': '1', 12:06:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:17 INFO - 'NO_EM_RESTART': '1', 12:06:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:17 INFO - 'PWD': '/builds/slave/test', 12:06:17 INFO - 'SHELL': '/bin/bash', 12:06:17 INFO - 'SHLVL': '1', 12:06:17 INFO - 'TERM': 'linux', 12:06:17 INFO - 'TMOUT': '86400', 12:06:17 INFO - 'USER': 'cltbld', 12:06:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:17 INFO - '_': '/tools/buildbot/bin/python'} 12:06:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:17 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 12:06:17 INFO - Cleaning up... 12:06:17 INFO - Return code: 0 12:06:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:06:17 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')]} 12:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:17 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')]} 12:06:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:17 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:17 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 12:06:17 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 12:06:17 INFO - Using env: {'DISPLAY': ':0', 12:06:17 INFO - 'HOME': '/home/cltbld', 12:06:17 INFO - 'LANG': 'en_US.UTF-8', 12:06:17 INFO - 'LANGUAGE': 'en_US:en', 12:06:17 INFO - 'LOGNAME': 'cltbld', 12:06:17 INFO - 'MAIL': '/var/mail/cltbld', 12:06:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:17 INFO - 'MOZ_NO_REMOTE': '1', 12:06:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:17 INFO - 'NO_EM_RESTART': '1', 12:06:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:17 INFO - 'PWD': '/builds/slave/test', 12:06:17 INFO - 'SHELL': '/bin/bash', 12:06:17 INFO - 'SHLVL': '1', 12:06:17 INFO - 'TERM': 'linux', 12:06:17 INFO - 'TMOUT': '86400', 12:06:17 INFO - 'USER': 'cltbld', 12:06:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:17 INFO - '_': '/tools/buildbot/bin/python'} 12:06:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:17 INFO - Downloading/unpacking psutil>=3.1.1 12:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:06:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:19 INFO - Installing collected packages: psutil 12:06:19 INFO - Running setup.py install for psutil 12:06:19 INFO - building 'psutil._psutil_linux' extension 12:06:19 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 12:06:19 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 12:06:19 INFO - building 'psutil._psutil_posix' extension 12:06:19 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 12:06:19 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 12:06:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:20 INFO - Successfully installed psutil 12:06:20 INFO - Cleaning up... 12:06:20 INFO - Return code: 0 12:06:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:20 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:20 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 12:06:20 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 12:06:20 INFO - Using env: {'DISPLAY': ':0', 12:06:20 INFO - 'HOME': '/home/cltbld', 12:06:20 INFO - 'LANG': 'en_US.UTF-8', 12:06:20 INFO - 'LANGUAGE': 'en_US:en', 12:06:20 INFO - 'LOGNAME': 'cltbld', 12:06:20 INFO - 'MAIL': '/var/mail/cltbld', 12:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:20 INFO - 'MOZ_NO_REMOTE': '1', 12:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:20 INFO - 'NO_EM_RESTART': '1', 12:06:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:20 INFO - 'PWD': '/builds/slave/test', 12:06:20 INFO - 'SHELL': '/bin/bash', 12:06:20 INFO - 'SHLVL': '1', 12:06:20 INFO - 'TERM': 'linux', 12:06:20 INFO - 'TMOUT': '86400', 12:06:20 INFO - 'USER': 'cltbld', 12:06:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:20 INFO - '_': '/tools/buildbot/bin/python'} 12:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:06:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:06:22 INFO - Installing collected packages: mozsystemmonitor 12:06:22 INFO - Running setup.py install for mozsystemmonitor 12:06:22 INFO - Successfully installed mozsystemmonitor 12:06:22 INFO - Cleaning up... 12:06:22 INFO - Return code: 0 12:06:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:22 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:22 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 12:06:22 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 12:06:22 INFO - Using env: {'DISPLAY': ':0', 12:06:22 INFO - 'HOME': '/home/cltbld', 12:06:22 INFO - 'LANG': 'en_US.UTF-8', 12:06:22 INFO - 'LANGUAGE': 'en_US:en', 12:06:22 INFO - 'LOGNAME': 'cltbld', 12:06:22 INFO - 'MAIL': '/var/mail/cltbld', 12:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:22 INFO - 'MOZ_NO_REMOTE': '1', 12:06:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:22 INFO - 'NO_EM_RESTART': '1', 12:06:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:22 INFO - 'PWD': '/builds/slave/test', 12:06:22 INFO - 'SHELL': '/bin/bash', 12:06:22 INFO - 'SHLVL': '1', 12:06:22 INFO - 'TERM': 'linux', 12:06:22 INFO - 'TMOUT': '86400', 12:06:22 INFO - 'USER': 'cltbld', 12:06:22 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:22 INFO - '_': '/tools/buildbot/bin/python'} 12:06:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:22 INFO - Downloading/unpacking jsonschema==2.5.1 12:06:22 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 12:06:22 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 12:06:22 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 12:06:22 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 12:06:22 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 12:06:22 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 12:06:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:06:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:06:24 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 12:06:24 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 12:06:24 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 12:06:24 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 12:06:24 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 12:06:24 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 12:06:24 INFO - Downloading functools32-3.2.3-2.tar.gz 12:06:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:06:24 INFO - warning: no files found matching '*.txt' 12:06:24 INFO - no previously-included directories found matching 'build' 12:06:24 INFO - no previously-included directories found matching 'dist' 12:06:24 INFO - no previously-included directories found matching '.git*' 12:06:24 INFO - Installing collected packages: jsonschema, functools32 12:06:24 INFO - Running setup.py install for functools32 12:06:24 INFO - warning: no files found matching '*.txt' 12:06:24 INFO - no previously-included directories found matching 'build' 12:06:24 INFO - no previously-included directories found matching 'dist' 12:06:24 INFO - no previously-included directories found matching '.git*' 12:06:24 INFO - Successfully installed jsonschema functools32 12:06:24 INFO - Cleaning up... 12:06:24 INFO - Return code: 0 12:06:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:06:24 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')]} 12:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:24 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')]} 12:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:24 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:24 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 12:06:24 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 12:06:24 INFO - Using env: {'DISPLAY': ':0', 12:06:24 INFO - 'HOME': '/home/cltbld', 12:06:24 INFO - 'LANG': 'en_US.UTF-8', 12:06:24 INFO - 'LANGUAGE': 'en_US:en', 12:06:24 INFO - 'LOGNAME': 'cltbld', 12:06:24 INFO - 'MAIL': '/var/mail/cltbld', 12:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:24 INFO - 'MOZ_NO_REMOTE': '1', 12:06:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:24 INFO - 'NO_EM_RESTART': '1', 12:06:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:24 INFO - 'PWD': '/builds/slave/test', 12:06:24 INFO - 'SHELL': '/bin/bash', 12:06:24 INFO - 'SHLVL': '1', 12:06:24 INFO - 'TERM': 'linux', 12:06:24 INFO - 'TMOUT': '86400', 12:06:24 INFO - 'USER': 'cltbld', 12:06:24 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:24 INFO - '_': '/tools/buildbot/bin/python'} 12:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:06:25 INFO - Cleaning up... 12:06:25 INFO - Return code: 0 12:06:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:25 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:25 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 12:06:25 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 12:06:25 INFO - Using env: {'DISPLAY': ':0', 12:06:25 INFO - 'HOME': '/home/cltbld', 12:06:25 INFO - 'LANG': 'en_US.UTF-8', 12:06:25 INFO - 'LANGUAGE': 'en_US:en', 12:06:25 INFO - 'LOGNAME': 'cltbld', 12:06:25 INFO - 'MAIL': '/var/mail/cltbld', 12:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:25 INFO - 'MOZ_NO_REMOTE': '1', 12:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:25 INFO - 'NO_EM_RESTART': '1', 12:06:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:25 INFO - 'PWD': '/builds/slave/test', 12:06:25 INFO - 'SHELL': '/bin/bash', 12:06:25 INFO - 'SHLVL': '1', 12:06:25 INFO - 'TERM': 'linux', 12:06:25 INFO - 'TMOUT': '86400', 12:06:25 INFO - 'USER': 'cltbld', 12:06:25 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:25 INFO - '_': '/tools/buildbot/bin/python'} 12:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:25 INFO - Downloading/unpacking blobuploader==1.2.4 12:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:27 INFO - Downloading blobuploader-1.2.4.tar.gz 12:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:06:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:06:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:27 INFO - Downloading docopt-0.6.1.tar.gz 12:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:06:28 INFO - Installing collected packages: blobuploader, requests, docopt 12:06:28 INFO - Running setup.py install for blobuploader 12:06:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:06:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:06:28 INFO - Running setup.py install for requests 12:06:28 INFO - Running setup.py install for docopt 12:06:28 INFO - Successfully installed blobuploader requests docopt 12:06:28 INFO - Cleaning up... 12:06:28 INFO - Return code: 0 12:06:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:28 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')]} 12:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:28 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')]} 12:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:28 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:28 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 12:06:28 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 12:06:28 INFO - Using env: {'DISPLAY': ':0', 12:06:28 INFO - 'HOME': '/home/cltbld', 12:06:28 INFO - 'LANG': 'en_US.UTF-8', 12:06:28 INFO - 'LANGUAGE': 'en_US:en', 12:06:28 INFO - 'LOGNAME': 'cltbld', 12:06:28 INFO - 'MAIL': '/var/mail/cltbld', 12:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:28 INFO - 'MOZ_NO_REMOTE': '1', 12:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:28 INFO - 'NO_EM_RESTART': '1', 12:06:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:28 INFO - 'PWD': '/builds/slave/test', 12:06:28 INFO - 'SHELL': '/bin/bash', 12:06:28 INFO - 'SHLVL': '1', 12:06:28 INFO - 'TERM': 'linux', 12:06:28 INFO - 'TMOUT': '86400', 12:06:28 INFO - 'USER': 'cltbld', 12:06:28 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:28 INFO - '_': '/tools/buildbot/bin/python'} 12:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:28 INFO - Running setup.py (path:/tmp/pip-rlCaFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:28 INFO - Running setup.py (path:/tmp/pip-CqhG88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:29 INFO - Running setup.py (path:/tmp/pip-leST_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:29 INFO - Running setup.py (path:/tmp/pip-7_2EUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:29 INFO - Running setup.py (path:/tmp/pip-CDJY9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:29 INFO - Running setup.py (path:/tmp/pip-svecSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:29 INFO - Running setup.py (path:/tmp/pip-dag9RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:29 INFO - Running setup.py (path:/tmp/pip-Qg4bIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:29 INFO - Running setup.py (path:/tmp/pip-OSTxXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:29 INFO - Running setup.py (path:/tmp/pip-5y0pSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:29 INFO - Running setup.py (path:/tmp/pip-_7WKBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:29 INFO - Running setup.py (path:/tmp/pip-7So2Vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:29 INFO - Running setup.py (path:/tmp/pip-k0mS2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:29 INFO - Running setup.py (path:/tmp/pip-9uOxNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:29 INFO - Running setup.py (path:/tmp/pip-COwaqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:29 INFO - Running setup.py (path:/tmp/pip-6X9pP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:30 INFO - Running setup.py (path:/tmp/pip-TXyRDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:06:30 INFO - Running setup.py install for manifestparser 12:06:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Running setup.py install for mozcrash 12:06:30 INFO - Running setup.py install for mozdebug 12:06:30 INFO - Running setup.py install for mozdevice 12:06:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Running setup.py install for mozfile 12:06:30 INFO - Running setup.py install for mozhttpd 12:06:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Running setup.py install for mozinfo 12:06:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Running setup.py install for mozInstall 12:06:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:06:30 INFO - Running setup.py install for mozleak 12:06:30 INFO - Running setup.py install for mozlog 12:06:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Running setup.py install for moznetwork 12:06:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Running setup.py install for mozprocess 12:06:31 INFO - Running setup.py install for mozprofile 12:06:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Running setup.py install for mozrunner 12:06:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Running setup.py install for mozscreenshot 12:06:31 INFO - Running setup.py install for moztest 12:06:31 INFO - Running setup.py install for mozversion 12:06:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:06:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:06:31 INFO - Cleaning up... 12:06:31 INFO - Return code: 0 12:06:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:31 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')]} 12:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:31 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')]} 12:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:31 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:31 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 12:06:31 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 12:06:31 INFO - Using env: {'DISPLAY': ':0', 12:06:31 INFO - 'HOME': '/home/cltbld', 12:06:31 INFO - 'LANG': 'en_US.UTF-8', 12:06:31 INFO - 'LANGUAGE': 'en_US:en', 12:06:31 INFO - 'LOGNAME': 'cltbld', 12:06:31 INFO - 'MAIL': '/var/mail/cltbld', 12:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:31 INFO - 'MOZ_NO_REMOTE': '1', 12:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:31 INFO - 'NO_EM_RESTART': '1', 12:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:31 INFO - 'PWD': '/builds/slave/test', 12:06:31 INFO - 'SHELL': '/bin/bash', 12:06:31 INFO - 'SHLVL': '1', 12:06:31 INFO - 'TERM': 'linux', 12:06:31 INFO - 'TMOUT': '86400', 12:06:31 INFO - 'USER': 'cltbld', 12:06:31 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:31 INFO - '_': '/tools/buildbot/bin/python'} 12:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:32 INFO - Running setup.py (path:/tmp/pip-Rg8wE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:32 INFO - Running setup.py (path:/tmp/pip-wzhcTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:32 INFO - Running setup.py (path:/tmp/pip-wT3qQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:32 INFO - Running setup.py (path:/tmp/pip-o9_bW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:32 INFO - Running setup.py (path:/tmp/pip-OlE3CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:32 INFO - Running setup.py (path:/tmp/pip-c2OYcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:32 INFO - Running setup.py (path:/tmp/pip-zhaS6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:32 INFO - Running setup.py (path:/tmp/pip-tK2IQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:32 INFO - Running setup.py (path:/tmp/pip-PO9Kcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:32 INFO - Running setup.py (path:/tmp/pip-714rF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:32 INFO - Running setup.py (path:/tmp/pip-QRSTVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:32 INFO - Running setup.py (path:/tmp/pip-xyfjlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:32 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)) 12:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:33 INFO - Running setup.py (path:/tmp/pip-pqZU7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:33 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)) 12:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:33 INFO - Running setup.py (path:/tmp/pip-FhNGxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:33 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)) 12:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:33 INFO - Running setup.py (path:/tmp/pip-DqeIuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:33 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)) 12:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:33 INFO - Running setup.py (path:/tmp/pip-CzrqzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:33 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)) 12:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:33 INFO - Running setup.py (path:/tmp/pip-eNtBoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:33 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)) 12:06:33 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)) 12:06:33 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)) 12:06:33 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)) 12:06:33 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)) 12:06:33 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)) 12:06:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:33 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 12:06:33 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 12:06:33 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 12:06:33 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 12:06:33 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 12:06:33 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 12:06:35 INFO - Downloading blessings-1.6.tar.gz 12:06:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:06:35 INFO - Installing collected packages: blessings 12:06:35 INFO - Running setup.py install for blessings 12:06:35 INFO - Successfully installed blessings 12:06:35 INFO - Cleaning up... 12:06:35 INFO - Return code: 0 12:06:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:35 INFO - Reading from file tmpfile_stdout 12:06:35 INFO - Current package versions: 12:06:35 INFO - argparse == 1.2.1 12:06:35 INFO - blessings == 1.6 12:06:35 INFO - blobuploader == 1.2.4 12:06:35 INFO - docopt == 0.6.1 12:06:35 INFO - functools32 == 3.2.3-2 12:06:35 INFO - jsonschema == 2.5.1 12:06:35 INFO - manifestparser == 1.1 12:06:35 INFO - mozInstall == 1.12 12:06:35 INFO - mozcrash == 0.17 12:06:35 INFO - mozdebug == 0.1 12:06:35 INFO - mozdevice == 0.48 12:06:35 INFO - mozfile == 1.2 12:06:35 INFO - mozhttpd == 0.7 12:06:35 INFO - mozinfo == 0.9 12:06:35 INFO - mozleak == 0.1 12:06:35 INFO - mozlog == 3.4 12:06:35 INFO - moznetwork == 0.27 12:06:35 INFO - mozprocess == 0.23 12:06:35 INFO - mozprofile == 0.28 12:06:35 INFO - mozrunner == 6.12 12:06:35 INFO - mozscreenshot == 0.1 12:06:35 INFO - mozsystemmonitor == 0.3 12:06:35 INFO - moztest == 0.7 12:06:35 INFO - mozversion == 1.4 12:06:35 INFO - psutil == 3.1.1 12:06:35 INFO - requests == 1.2.3 12:06:35 INFO - wsgiref == 0.1.2 12:06:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:35 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:35 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 12:06:35 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 12:06:35 INFO - Using env: {'DISPLAY': ':0', 12:06:35 INFO - 'HOME': '/home/cltbld', 12:06:35 INFO - 'LANG': 'en_US.UTF-8', 12:06:35 INFO - 'LANGUAGE': 'en_US:en', 12:06:35 INFO - 'LOGNAME': 'cltbld', 12:06:35 INFO - 'MAIL': '/var/mail/cltbld', 12:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:35 INFO - 'MOZ_NO_REMOTE': '1', 12:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:35 INFO - 'NO_EM_RESTART': '1', 12:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:35 INFO - 'PWD': '/builds/slave/test', 12:06:35 INFO - 'SHELL': '/bin/bash', 12:06:35 INFO - 'SHLVL': '1', 12:06:35 INFO - 'TERM': 'linux', 12:06:35 INFO - 'TMOUT': '86400', 12:06:35 INFO - 'USER': 'cltbld', 12:06:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:35 INFO - '_': '/tools/buildbot/bin/python'} 12:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 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)) 12:06:35 INFO - Cleaning up... 12:06:35 INFO - Return code: 0 12:06:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:35 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 0x7f2df69bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', '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 12:06:35 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 12:06:35 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 12:06:35 INFO - Using env: {'DISPLAY': ':0', 12:06:35 INFO - 'HOME': '/home/cltbld', 12:06:35 INFO - 'LANG': 'en_US.UTF-8', 12:06:35 INFO - 'LANGUAGE': 'en_US:en', 12:06:35 INFO - 'LOGNAME': 'cltbld', 12:06:35 INFO - 'MAIL': '/var/mail/cltbld', 12:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:35 INFO - 'MOZ_NO_REMOTE': '1', 12:06:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:35 INFO - 'NO_EM_RESTART': '1', 12:06:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:35 INFO - 'PWD': '/builds/slave/test', 12:06:35 INFO - 'SHELL': '/bin/bash', 12:06:35 INFO - 'SHLVL': '1', 12:06:35 INFO - 'TERM': 'linux', 12:06:35 INFO - 'TMOUT': '86400', 12:06:35 INFO - 'USER': 'cltbld', 12:06:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:35 INFO - '_': '/tools/buildbot/bin/python'} 12:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:06:36 INFO - Cleaning up... 12:06:36 INFO - Return code: 0 12:06:36 INFO - Running post-action listener: _resource_record_post_action 12:06:36 INFO - Running post-action listener: _start_resource_monitoring 12:06:36 INFO - Starting resource monitoring. 12:06:36 INFO - [mozharness: 2016-09-21 19:06:36.184765Z] Finished create-virtualenv step (success) 12:06:36 INFO - [mozharness: 2016-09-21 19:06:36.185218Z] Running install step. 12:06:36 INFO - Running pre-action listener: _resource_record_pre_action 12:06:36 INFO - Running main action method: install 12:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:36 INFO - Reading from file tmpfile_stdout 12:06:36 INFO - Detecting whether we're running mozinstall >=1.0... 12:06:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:06:36 INFO - Reading from file tmpfile_stdout 12:06:36 INFO - Output received: 12:06:36 INFO - Usage: mozinstall [options] installer 12:06:36 INFO - Options: 12:06:36 INFO - -h, --help show this help message and exit 12:06:36 INFO - -d DEST, --destination=DEST 12:06:36 INFO - Directory to install application into. [default: 12:06:36 INFO - "/builds/slave/test"] 12:06:36 INFO - --app=APP Application being installed. [default: firefox] 12:06:36 INFO - mkdir: /builds/slave/test/build/application 12:06:36 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'] 12:06:36 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 12:06:52 INFO - Reading from file tmpfile_stdout 12:06:52 INFO - Output received: 12:06:52 INFO - /builds/slave/test/build/application/firefox/firefox 12:06:52 INFO - Running post-action listener: _resource_record_post_action 12:06:52 INFO - [mozharness: 2016-09-21 19:06:52.543214Z] Finished install step (success) 12:06:52 INFO - [mozharness: 2016-09-21 19:06:52.543458Z] Running run-tests step. 12:06:52 INFO - Running pre-action listener: _resource_record_pre_action 12:06:52 INFO - Running main action method: run_tests 12:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:06:52 INFO - Python 2.7.3 12:06:52 INFO - Return code: 0 12:06:52 INFO - grabbing minidump binary from tooltool 12:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:52 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 0x1a4ff60>, '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 0x1a50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a511d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:06:52 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 12:06:52 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 12:06:52 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 12:06:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:06:52 INFO - Return code: 0 12:06:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:06:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:06:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:06:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:06:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 12:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 12:06:52 INFO - Using env: {'DISPLAY': ':0', 12:06:52 INFO - 'HOME': '/home/cltbld', 12:06:52 INFO - 'LANG': 'en_US.UTF-8', 12:06:52 INFO - 'LANGUAGE': 'en_US:en', 12:06:52 INFO - 'LOGNAME': 'cltbld', 12:06:52 INFO - 'MAIL': '/var/mail/cltbld', 12:06:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:52 INFO - 'MOZ_NO_REMOTE': '1', 12:06:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:52 INFO - 'NO_EM_RESTART': '1', 12:06:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:52 INFO - 'PWD': '/builds/slave/test', 12:06:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:52 INFO - 'SHELL': '/bin/bash', 12:06:52 INFO - 'SHLVL': '1', 12:06:52 INFO - 'TERM': 'linux', 12:06:52 INFO - 'TMOUT': '86400', 12:06:52 INFO - 'USER': 'cltbld', 12:06:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189', 12:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:52 INFO - '_': '/tools/buildbot/bin/python'} 12:06:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 12:06:52 INFO - mozversion application_buildid: 20160921094107 12:06:52 INFO - mozversion application_changeset: 26e0cc5ad055a64c8c38ef067a179a4e1b179cac 12:06:52 INFO - mozversion application_display_name: Nightly 12:06:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:06:52 INFO - mozversion application_name: Firefox 12:06:52 INFO - mozversion application_remotingname: firefox 12:06:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:06:52 INFO - mozversion application_vendor: Mozilla 12:06:52 INFO - mozversion application_version: 52.0a1 12:06:52 INFO - mozversion platform_buildid: 20160921094107 12:06:52 INFO - mozversion platform_changeset: 26e0cc5ad055a64c8c38ef067a179a4e1b179cac 12:06:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:06:52 INFO - mozversion platform_version: 52.0a1 12:06:52 INFO - using testdate: 1474484812 12:06:52 INFO - actual date: 1474484812 12:06:52 INFO - starting webserver on 'localhost:53777' 12:06:52 INFO - SUITE-START | Running 2 tests 12:06:52 INFO - TEST-START | dromaeo_css 12:06:52 INFO - operating with platform_type : linux_ 12:06:52 INFO - Initialising browser for dromaeo_css test... 12:06:52 INFO - TEST-INFO | started process 5108 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHrVHwp/profile http://localhost:53777/getInfo.html) 12:06:57 INFO - PROCESS | 5108 | __metrics Screen width/height:1600/1200 12:06:57 INFO - PROCESS | 5108 | colorDepth:24 12:06:57 INFO - PROCESS | 5108 | Browser inner width/height: 1024/697 12:06:57 INFO - PROCESS | 5108 | __metrics 12:06:58 ERROR - PROCESS | 5108 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 12:06:59 INFO - TEST-INFO | 5108: exit 0 12:06:59 INFO - Browser initialized. 12:06:59 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:59 INFO - TEST-INFO | started process 5228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHrVHwp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:11 INFO - PROCESS | 5228 | RSS: Main: 185884672 12:07:11 INFO - PROCESS | 5228 | 12:08:01 INFO - PROCESS | 5228 | 1474484881093 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] 12:10:13 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53777/tests/dromaeo/cssquery-ext.html) 12:10:15 INFO - PROCESS | 5228 | RSS: Main: 202162176 12:10:15 INFO - PROCESS | 5228 | 12:13:17 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-ext.html (next: http://localhost:53777/tests/dromaeo/cssquery-jquery.html) 12:13:18 INFO - PROCESS | 5228 | RSS: Main: 200228864 12:13:18 INFO - PROCESS | 5228 | 12:16:20 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53777/tests/dromaeo/cssquery-mootools.html) 12:16:20 INFO - PROCESS | 5228 | RSS: Main: 197746688 12:16:20 INFO - PROCESS | 5228 | 12:19:22 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53777/tests/dromaeo/cssquery-prototype.html) 12:19:23 INFO - PROCESS | 5228 | RSS: Main: 194891776 12:19:23 INFO - PROCESS | 5228 | 12:22:26 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53777/tests/dromaeo/cssquery-yui.html) 12:22:26 INFO - PROCESS | 5228 | RSS: Main: 197619712 12:22:26 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | Cycle 1(1): loaded http://localhost:53777/tests/dromaeo/cssquery-yui.html (next: http://localhost:53777/tests/dromaeo/cssquery-dojo.html) 12:25:29 INFO - PROCESS | 5228 | RSS: Main: 198819840 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | __start_tp_report 12:25:29 INFO - PROCESS | 5228 | _x_x_mozilla_page_load 12:25:29 INFO - PROCESS | 5228 | _x_x_mozilla_page_load_details 12:25:29 INFO - PROCESS | 5228 | |i|pagename|runs| 12:25:29 INFO - PROCESS | 5228 | |0;dojo.html;2595.7534034266746;2605.452854900471;2614.6339512468244;2630.934226644334;2650.9867450662755;4610.23931051377;4732.1482133216;4778.235482322824;4781.545753153449;4835.202191638376;4672.696274742139;4688.953110468894;4727.550882666142;4742.099001189771;4782.402199725035;4314.762688052156;4359.716618419809;4405.757683327416;4441.178381999324;4450.376947227391;4480.171168333869;4492.326151077332;4507.256302710049;4523.253663145577;4524.027334123163;4288.09949910516;4294.076773493683;4349.869034051169;4359.738415695037;4402.779861006937;4343.370211319341;4345.956540434587;4363.214621368153;4374.0595771908875;4415.801288942005;4331.917020744814;4352.260115780325;4355.7822108894425;4364.018095928423;4381.38660587518;4582.427196600425;4646.581807637295;4658.58072773452;4718.433787945469;4719.292106184065;5019.498050194988;5050.520200580939;5057.811414317633;5098.209777484496;5134.383873935151;5139.177731562932;5143.305653736773;5155.664881782672;5160.767765450564;5197.506236907526;4734.26618874075;4774.4509381421585;4777.761111944389;4791.065742180242;4792.041591681677;5222.216667499905;5246.921296180561;5261.500157485033;5276.551493123051;5326.9467305327225;4841.370621819211;5018.523240292239;5024.020316038337;5060.5951523878;5072.442031376546;4995.575376093;5095.694258344512;5128.948710512922;5163.948360516422;5180.4560521145495;5039.97480012605;5071.619628527875;5077.238414237894;5080.263361812516;5136.332276803992;5007.924881126786;5023.2214006829;5032.924506132411;5033.370828646419;5063.49365063498;4221.1346673932385;4266.679999000087;4316.6546676265825;4323.048929235563;4327.156204540147;3757.492738480325;3796.468494410785;3796.6393192646656;3825.3305671507374;3862.980685096613;4405.9559404405545;4438.842342008315;4453.599176074169;4484.887877803016;4504.549545045535;5007.974960125249;5052.646314757932;5066.847994560169;5073.847784566469;5186.81846135391;4346.413234213341;4375.431193944767;4416.712913660666;4419.955800441954;4428.557144285545;4559;4567.748773817406;4575.382323386367;4575.565321294472;4645.024544845553;4732.6687131900435;4761.2382018876815;4777.283407488904;4805;4812.807487700593;5105;5136.8202112925865;5143.665661731976;5175.482451754794;5190.143626301618;4766.046790641954;4819.59033482158;4820.71075735457;4821.975890120527;4862.562369386772;4902.92645610309;4965.577925876341;4985.451600324034;5066.316047333563;5077.289179514797;5149.356330458692;5181.119209734279;5183.377994640668;5185.818496352609;5221.791128354823;5009.323741295024;5014.648974571745;5019.924701129413;5021.1213037717525;5083.644144909821;4821.059893320909;4894.314795928644;4900.901981960412;4922.803087876445;5007.148784706682;4911.7298548580175;4931.753412329297;4974.179260422135;4980.8754781130765;4991.5258050485145;5041.470645582308;5044.4451110377095;5045.571126454292;5056.494350565061;5092.6435149539175;4771.904561908812;4913.459519452786;4923.5814955729165;4929.014197160654;4980.701157930535 12:25:29 INFO - PROCESS | 5228 | |1;ext.html;531.1740243920701;544.2054600283586;546.6091744402751;549.4176173256349;556.449115375778;36175;37713.62286377136;37918.62081379185;41935.79032104843;41961.37057944126;38289.617103828954;38712.61287387125;38739.80630096853;38755.61244387555;38837.61162388375;7538.811529711774;7672.424568157383;7674.386049116058;7676.270754278363;7678.42411819113;7602.885956710654;7700.653470593837;7705.576193309365;7713.228677132298;7715.151333353355;7690.846183076335;7692.076950765916;7721.266479684449;7738.651760670783;7738.883916741253;7628.923710762877;7659.731909383145;7693.461457697963;7713.537187768696;7715.537067775951;7642.885356719654;7688;7697.615119244071;7702.229777022309;7703;37364.81317593422;37625.24749505022;37685.623143768484;37702.62297377019;37760.24479510422;2767.4603452326805;2807.761340286078;2902.7097290271017;3098.907032788997;3569.8929032129076;27482.862585686944;27927.85587170819;28026.579601305995;28043.3728434808;29048.564271535943;3524.7004004659634;3526.0832183632183;3539.2213712983103;3691.9815400923094;3714.182879766447;242277;243159;243544;243778;244127;48335.516644833806;48903.51096489061;48920.90646856186;49048;49059.50940490621;31410;31906.36187276288;31972;34143.48784768231;34147.317053658786;14270.500532481314;14548.636284092983;14595.635109122357;14641.853581464262;14649.780253296209;10649.201309901788;10830.241883068109;10831.891681083245;10854.511546980404;10865.348079115278;17002.91929936831;17227.913860430617;17228;17275.222614982355;17276.740848887275;6786.423154031864;6902.827429314207;6916;7021.929780702128;7098.893516597255;735.5733674468795;875.159846547309;905.9048799875918;960.7742180587557;965.9712406287282;25918;26904.461910761675;27155.321116971838;27389.86305068462;27481.725182748316;855.2516548020482;866.8526350520426;918.8300164469595;989.8663680403198;1000.2098342309639;11502.769944601061;13495.392707328194;13707.451701931812;14029.883324073364;14279.64300892486;383457;387655;387693;388829;389544;45542.31686524705;45895.770521147184;46116;46168.3074753879;46193.30710039352;1119.6696974392573;1157.5659127827064;1175.6531823112032;1183.7099910521977;1184.7926612842891;25006.37484062913;25341.366465838502;25642.230733077293;25980.220593381473;26035.609465858408;38216.80891595524;38965.610343896195;39102.24039918267;39111.60888391193;39119;338414;340378;341113;342102;342482;23629.40926476783;23957.52084958326;23965.400864978514;23971.400714981566;24181.274561763174;32062.198445038128;32695.70920364898;32763;33091.50362744513;33182.336353272316;32253.21322039154;32923.835380822944;33095.33809323848;33351;33642.663573363956;33376.165595859326;33390;33847.66152338445;33851.492227616116;34324.828375857964;32531.674683252866;33024;33181;36843.63156368509;37214;33952.490712638835;36774.63225367819;36921;36979.63020369762;37164.44253336148;29291.707082928897;29557.408851823275;29917.850410748677;30485.390292194475;30599.2350191247 12:25:29 INFO - PROCESS | 5228 | |2;jquery.html;361.2666287436503;366.5656197406074;370.69788122680023;382.11159055196674;384.17977617786016;15057.322420491077;15444.227788610566;15601.375944962216;15757;15763.290651920663;16030.679386412265;16785.83214167858;16911.661766764657;17070.14649267538;17089.743653845202;4832.71003739775;5063.392392912856;5106.417010726686;5120.667156634807;5121.641485096045;5098.439171691111;5161.4838516148275;5162.457941916082;5163.251328557357;5169.819056333029;5227.921581176266;5512.972435137819;5553.333599968009;5672.631278966875;5687.431256874321;5361.892762144736;5521.558275337963;5591.2731344925105;5637.661740295575;5639.8590035249035;5238.842834714965;5280.313559237313;5293.470652934714;5296.25852380667;5303.257543943851;10976.780464390667;10999.780004399867;11025.61410350634;11056.944715276371;11219;1585.7700633408092;1669.5575672446812;1671.523615769512;1703.2165204006171;1751.2469638055632;8982.281417486585;9070.410423322463;9101.772455688595;9160.954195229047;9610.711678649583;1659.444086231114;1725.0857045765765;1740.2342969093559;1764.373647355196;1778.7776527934009;66955;69147.78748675283;70637.29362706358;71963;72541;10069.69790906274;10144.847827282596;10295.176385889112;10483.423411712276;10517.789644207072;14040.789388159186;14076.155430674193;14077.436902523785;14100.365483553265;14158.362873670709;7029.683664235122;7307.926920730831;7309.64771517271;7625.046869141357;7670.194629563815;6962.303769622998;6963;7198.5680859148615;7234.963825180946;7296.379807716404;4283.2932566126865;4361.716488428242;4368.934465983013;4507.752073635981;4544.568266014723;1715.7340612205128;1760.1551255397226;1785.7232129020022;1810.8189181081787;1820.5812663087563;367.3067160390597;373.92895349883435;765.1659690936906;784.3058892879842;873.886394768676;9941.403515789076;9986.500674966368;10207.897921020694;10274;10762.677119686421;378.128414005714;382.96744776693737;383.7122158381214;384.30249097887247;387.75571390024106;5538.86152846174;5584.5811564132855;5647.915281270784;5652.63257888236;5696.287964004499;383904;388146;398633;400647;404515;81108.59445702734;81133;81912;82934.58532707297;83050.58474707708;899.8155378147464;903.1420150856712;931.7421481000595;944.4191822029402;945.6595625574788;15670.216489175724;15875.761863572287;16080.195990200209;16193.59516012109;16578;37282.254354912206;38855.80572097121;38956.02609934774;39262.60737392589;39498.80250598844;54100.729496353844;55777.442225578845;56294.43705562892;56953.71523142524;57339.42660573341;7616.619169041636;7640.541567505968;7687.192844751221;7708.8072798178255;7875.212478752079;10672.199585030998;11187.664370068911;11330.640323161273;12093.455794489093;12341.753164936472;9728.659496917573;9900.50497475109;10276;10413.739656508345;10526.263161578616;9979.650712225037;10315.432651203955;10322.380657160595;10368.637097701545;10430.322029068086;9704.563294651898;10424.322419042737;10463.372197668165;10515.894841051491;10859.131269498579;7713.845723085619;7752.8449431012195;7768.7669369918995;7782.210091679059;7827.45207835446;10469.842952355568;10564.630237941636;10829.566817327219;10949.890501095206;11002.394868282321 12:25:29 INFO - PROCESS | 5228 | |3;mootools.html;1358.0561509750723;1405.4799724102081;1460.7954886315918;1625.06558728731;1636.5908522869274;6937.583744975293;7155.570665760058;7457.701691932323;8091.595420228987;8099.595020248986;7462.029936108298;7800.1809809969845;7821.6480258388365;8062.798430039237;8072.55600941948;2155.234891613482;2193.967090493636;2194.561087782442;2195.648696208599;2201.7137772089604;2168.30614203455;2203.4711669199355;2209.0942713487475;2219.04581030157;2221.2669818959703;2110.8311335093154;2122.490602255455;2132.3709505695856;2143.2927134045726;2217.246136313653;2179.259051922338;2181.879996600186;2186.180182431588;2189.7591264960843;2192.0464597899963;2143.6248656485054;2170;2195.83531235158;2198.7471440784357;2212.2478357358577;7830.099538553025;7848.058233012018;7930.167332430127;8102.39232057598;8125.878111828328;4474.082813023322;4648.86053418398;4651.372064771246;4710.1286262041285;4726.527347265281;2909.11272062021;3022.2293315204483;3064.1573567268956;3080.7227349538425;3097.365040166783;910.7996240827008;979.8481235408523;982.6413359123868;990.9256805739599;1008.4755926917961;10706.340517219922;10760.892391076068;10780.40707761073;10791.730206744816;10819.513121909533;3996.2806694794635;4137.110521237888;4250.702450828412;4274.380214868826;4301.956980430155;9241.168294853496;9244.907550924405;9254.12085851843;9256.490893000815;9262.675806346746;4544.772761361919;4578.542145785462;4581.312803079498;4619.076184763061;4627.953720462752;3676.227992121631;3764.5858955514873;4007.6393124618926;4161;4167.895802604899;3500.8949731508096;3587.9282414351565;3684.060564556021;3727.1800203955086;3745.606711295329;1126.9887301127048;1128.729105014807;1134.9432528373547;1141.6632093532428;1147.2141583015662;632.585656395062;639.405353021685;784.7292684024003;805.017878188609;824.7257986411009;4860.586850117709;5034.219695947134;5101.158308878993;5106.361704786902;5204.42751297357;406.9165821006746;415.47442485256175;425.28976609062937;427.1265262538117;427.3056283539249;3323.321722530155;3445.0139910358416;3478.4608385700258;3481.860725570949;3509.0876372143425;9094;9817.803643927082;9851.261155413373;9853.75365615851;9856.802863942825;9774.266929980138;9775.560099795526;9802.607895684092;9803.01969803014;9816.656417025513;7030.543014704028;7247.782566523013;7300.269973002763;7474.2809153894905;7478.588677622783;5027.271045698426;5112.897742045064;5220.086484865209;5293.920591191212;5296;9027.819443611223;9060.682876099305;9096.226820720312;9241.491717955578;9242.537873106183;9006.459612423276;9134.36059475857;9273.489958052372;9331.72004839856;9365.39124956903;6717.429018533479;6722.294159113223;6736.831579210363;6769.796906092825;6813.250542440229;7312.780616581511;7359.889601655872;7366.079240094989;7389.926100738924;7390.704371825067;6507.707153178213;6533.18335208099;6644.435223005905;6653.733850646114;6819.659017049028;6338.461230795434;6406.807795766134;6425.261094974137;6441.194850643669;6465.935340646533;5788.247527821356;5884;5884.911726324194;5898.882022359443;5902.822915312548;4477.462704475483;4574.565416285448;4578.702384345007;4647.976760116313;4673.462551806586;4533.523979982055;4590.77046147698;4692.906141877212;4729.243321068612;4836 12:25:29 INFO - PROCESS | 5228 | |4;prototype.html;293.8369205091174;296.99109026729207;303.57651076747936;305.5676218151316;305.8256793627633;4899.853004409864;4916.262560615909;4921.852344429664;4924.384451943507;4926.871436637738;4832.323474713543;4850.2724591311235;4852.636052296074;4854.466008739036;4862.32249873793;3558.572971243448;3674.65090816372;3772.226693527834;3852.9422058669143;3887.378019516878;3875.1087249932534;3880.9611903881023;3881.359575670025;3885.514310711161;3891.455196272524;3857.32496813058;3864.111254411487;3867.439221312908;3869.8645547405845;3876.554196267423;3778.773273603565;3802.16352402471;3841.615838416136;3871.845126194963;3881.4177873319086;3393.117789374756;3395.151212196951;3409.573803274591;3414.3854106260865;3415.9487607685905;4758.262469317261;4781.665283430161;4832.903341933177;4836.395450568679;4841.419029716421;4374.365716971021;4393.5606439356125;4394.8901277468;4419;4422.059330545184;4937.7777999990085;5053.722045287508;5117.750228604826;5120.820771272987;5147.382314122329;3670.3576874046935;3677.7793332400142;3684.9263014739554;3688.870889518854;3693.2059607184583;5339.786408543692;5345.786168553253;5347.919781203285;5363.115086010804;5371.5165635092635;3655.79190567239;3776.244751049801;3783.659470647655;3788.2234142000825;3827.885163445101;3852.9019229519454;3859.614038596174;3863.96136038636;3869.342211823997;3872.477215575861;4312.525622181557;4361.323994780814;4361.738295702268;4367.650587952956;4371.759553224603;5005.4744251853035;5110.897782044412;5132;5133.25567792668;5136.5890728741615;1306.4578200046874;1334.726381091874;1335.6660834791303;1337.1413734908485;1341.7249463859885;818.8617821228497;840.9159084091542;853.1809462915546;864.05817658751;865.7705707987388;4316.568343165721;4373.190959672471;4426.535213802569;4521.59305662492;4540.318952157203;5321.600879934021;5332.68003919766;5335.519803217651;5341.4925582069645;5344.198370244494;4526.207913615104;4533.342665313577;4538.863834084983;4539.205639013159;4546.499885012646;2692.636494073314;2770.6813716422466;2803.4953708332696;2810.353618667718;2821.238265668258;5017.573506251936;5018.419197952633;5030.195168772979;5036;5043.798248070109;5274.182501712241;5445.047116754552;5445.1832225166545;5457;5463.262459567987;5071.873203169876;5091.821786237464;5102.413222479388;5106.4893510648635;5106.591472682177;5393;5393.381985404441;5412.485813847678;5414.810481633124;5428.91856622143;5137.33214682089;5200.609954253522;5225.738713064407;5271.761136133083;5275.920861187008;5048.596112310933;5054.59563234933;5063.1645778446145;5063.468335824686;5068.797248110034;3735.234276973268;3755.136318646781;3757.7829080842434;3759.5112635357227;3761.39817629178;5116.850922612437;5328.72110693423;5334.199870019528;5338.652987555796;5356.3572371315695;5206.375234971828;5215.739213039409;5225.817096401608;5227.503387178212;5232.3982741985155;5181.766820493011;5182.32629758129;5190.987757387274;5202.947970520247;5205.3753549572775;4866.124097662313;5092.618053646065;5100.38795344561;5119.387392971202;5128.333316668809;4982.900341993068;5080.288759573737;5130.461301563282;5212.348456442945;5225.137852254487;5067.189249720027;5087.465816089258;5097.974510127426;5103.795848166032;5106.085218043234 12:25:29 INFO - PROCESS | 5228 | |5;yui.html;612.3570251236201;614.4715544631616;625.7177933547187;658.2232965101184;719.086759815035;13131.474741010361;14131.646708832284;14138.293085345744;14143;14168.716625667481;13843.653908652288;14445.92777036116;14462.783058254108;14965.326560304786;15085.321160547774;753.8040109571496;770.6840195519839;771.4946709905021;771.8147644565292;772.2856357868977;728.3299364584595;763.4617594595796;765.4603504529322;767.7696690992708;770.3759954436924;807.1726480357651;814.8899898513685;815.6615004773012;816.0859836982559;816.2042009041197;805.279275048831;811.0834756724922;811.1766556944735;811.7483580090161;814.3892080939296;590.3004939147124;592.1620906417414;592.4638202426811;592.5763079398208;592.943670351316;13884.4446222151;13889.722205555832;13946.58160255194;13950.23273719945;13970.51103211393;12119;12165.452554635063;12246.326452045134;12419.31693756843;12834.037447191404;10520.528225685506;10623.150147988143;10801.891981080167;10853.891461085366;11026.558937642485;3269.1663625775277;3331.816750078745;3338;3358.227607650254;3382.8646854125805;64813;66163.3383666162;66206;67577;67832.660836696;10194.388336699822;10323.174146068297;10452.947735261427;10546.525406356732;10609.15126789855;19028.52428689266;19181.616367672847;19210.903945480182;19260.32588859411;19550.413487595393;6935;6948.305169483011;6995.1955525114245;7009.9649501752165;7067.964660176666;3659.8902032939054;3774.1130834253927;3854.9614503855164;3897;3938.350172221609;2911.6214892064318;3018.54721791733;3036.7722420818527;3060.403221371811;3064.969350306513;454.0963482669464;456.0992040719619;465.3438651501367;471.24130150457734;472.33400904724175;464.6816930402685;485.09771824406573;534.7326336831584;549.6811849127497;560.7280468972546;7683.195633593809;7978.321842643324;8082.393820463489;8111.3916456266015;8230.753077407688;454.7544326063955;459.16890428324837;471.4460508902026;540.9540189083893;550.9201165830932;3535.505029295901;3558.448440491728;3704.444333350019;3704.7962362070343;3757.7745335279424;102839;103810;103924;105418.4729076365;107451.46274268735;87174;88431.11568884228;89646.55176724205;89730.55134724284;92661.53669231746;62068.689656552335;73321.6333918327;73743.78202724659;76615.23384766192;81446.59276703579;7670.8849367260655;8384;8396.916030839613;8473.66105355779;8576.142385761374;39653.40519892261;42311.36532951947;43253.05097805234;45029.54970450253;46212.30681539847;51407.48592514027;52195.73902130465;54240.72879635577;55410;63252;11519;11838.704032398915;12035.398230088636;12064.939675301568;12068.879311206776;11708.824367634663;12072;12397.380130993233;13098.410571524113;13233.07368484197;10272.280940334404;10796.244262901522;11662;12032.819507707216;12366.567170149001;10543.525541350504;10703.143748499946;10851.40317282557;10993;11444.256123351955;10968.23222374426;11299.43502824872;11368.488418021043;11687.649370518899;11943.940280298542;9506.144446999802;9686.9031309686;9835.409875407213;9946.35348702332;10190;10205.183585313009;10449.477526123816;10466.947665261625;10638.202134839763;10661.09380702649 12:25:29 INFO - PROCESS | 5228 | __end_tp_report 12:25:29 INFO - PROCESS | 5228 | __start_cc_report 12:25:29 INFO - PROCESS | 5228 | _x_x_mozilla_cycle_collect,344 12:25:29 INFO - PROCESS | 5228 | __end_cc_report 12:25:29 INFO - PROCESS | 5228 | __startTimestamp1474485929755__endTimestamp 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | ------- Summary: start ------- 12:25:29 INFO - PROCESS | 5228 | Number of tests: 6 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#0] dojo.html Cycles:165 Average:4715.86 Median:4820.71 stddev:502.17 (10.4%) stddev-sans-first:475.37 12:25:29 INFO - PROCESS | 5228 | Values: 2595.8 2605.5 2614.6 2630.9 2651.0 4610.2 4732.1 4778.2 4781.5 4835.2 4672.7 4689.0 4727.6 4742.1 4782.4 4314.8 4359.7 4405.8 4441.2 4450.4 4480.2 4492.3 4507.3 4523.3 4524.0 4288.1 4294.1 4349.9 4359.7 4402.8 4343.4 4346.0 4363.2 4374.1 4415.8 4331.9 4352.3 4355.8 4364.0 4381.4 4582.4 4646.6 4658.6 4718.4 4719.3 5019.5 5050.5 5057.8 5098.2 5134.4 5139.2 5143.3 5155.7 5160.8 5197.5 4734.3 4774.5 4777.8 4791.1 4792.0 5222.2 5246.9 5261.5 5276.6 5326.9 4841.4 5018.5 5024.0 5060.6 5072.4 4995.6 5095.7 5128.9 5163.9 5180.5 5040.0 5071.6 5077.2 5080.3 5136.3 5007.9 5023.2 5032.9 5033.4 5063.5 4221.1 4266.7 4316.7 4323.0 4327.2 3757.5 3796.5 3796.6 3825.3 3863.0 4406.0 4438.8 4453.6 4484.9 4504.5 5008.0 5052.6 5066.8 5073.8 5186.8 4346.4 4375.4 4416.7 4420.0 4428.6 4559.0 4567.7 4575.4 4575.6 4645.0 4732.7 4761.2 4777.3 4805.0 4812.8 5105.0 5136.8 5143.7 5175.5 5190.1 4766.0 4819.6 4820.7 4822.0 4862.6 4902.9 4965.6 4985.5 5066.3 5077.3 5149.4 5181.1 5183.4 5185.8 5221.8 5009.3 5014.6 5019.9 5021.1 5083.6 4821.1 4894.3 4900.9 4922.8 5007.1 4911.7 4931.8 4974.2 4980.9 4991.5 5041.5 5044.4 5045.6 5056.5 5092.6 4771.9 4913.5 4923.6 4929.0 4980.7 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#1] ext.html Cycles:180 Average:46393.04 Median:3532.65 stddev:86885.18 (2459.5%) stddev-sans-first:87060.68 12:25:29 INFO - PROCESS | 5228 | Values: 531.2 544.2 546.6 549.4 556.4 36175.0 37713.6 37918.6 41935.8 41961.4 38289.6 38712.6 38739.8 38755.6 38837.6 7538.8 7672.4 7674.4 7676.3 7678.4 7602.9 7700.7 7705.6 7713.2 7715.2 7690.8 7692.1 7721.3 7738.7 7738.9 7628.9 7659.7 7693.5 7713.5 7715.5 7642.9 7688.0 7697.6 7702.2 7703.0 37364.8 37625.2 37685.6 37702.6 37760.2 2767.5 2807.8 2902.7 3098.9 3569.9 27482.9 27927.9 28026.6 28043.4 29048.6 3524.7 3526.1 3539.2 3692.0 3714.2 242277.0 243159.0 243544.0 243778.0 244127.0 48335.5 48903.5 48920.9 49048.0 49059.5 31410.0 31906.4 31972.0 34143.5 34147.3 14270.5 14548.6 14595.6 14641.9 14649.8 10649.2 10830.2 10831.9 10854.5 10865.3 17002.9 17227.9 17228.0 17275.2 17276.7 6786.4 6902.8 6916.0 7021.9 7098.9 735.6 875.2 905.9 960.8 966.0 25918.0 26904.5 27155.3 27389.9 27481.7 855.3 866.9 918.8 989.9 1000.2 11502.8 13495.4 13707.5 14029.9 14279.6 383457.0 387655.0 387693.0 388829.0 389544.0 45542.3 45895.8 46116.0 46168.3 46193.3 1119.7 1157.6 1175.7 1183.7 1184.8 25006.4 25341.4 25642.2 25980.2 26035.6 38216.8 38965.6 39102.2 39111.6 39119.0 338414.0 340378.0 341113.0 342102.0 342482.0 23629.4 23957.5 23965.4 23971.4 24181.3 32062.2 32695.7 32763.0 33091.5 33182.3 32253.2 32923.8 33095.3 33351.0 33642.7 33376.2 33390.0 33847.7 33851.5 34324.8 32531.7 33024.0 33181.0 36843.6 37214.0 33952.5 36774.6 36921.0 36979.6 37164.4 29291.7 29557.4 29917.9 30485.4 30599.2 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#2] jquery.html Cycles:180 Average:24244.22 Median:204399.15 stddev:65538.53 (32.1%) stddev-sans-first:65697.85 12:25:29 INFO - PROCESS | 5228 | Values: 361.3 366.6 370.7 382.1 384.2 15057.3 15444.2 15601.4 15757.0 15763.3 16030.7 16785.8 16911.7 17070.1 17089.7 4832.7 5063.4 5106.4 5120.7 5121.6 5098.4 5161.5 5162.5 5163.3 5169.8 5227.9 5513.0 5553.3 5672.6 5687.4 5361.9 5521.6 5591.3 5637.7 5639.9 5238.8 5280.3 5293.5 5296.3 5303.3 10976.8 10999.8 11025.6 11056.9 11219.0 1585.8 1669.6 1671.5 1703.2 1751.2 8982.3 9070.4 9101.8 9161.0 9610.7 1659.4 1725.1 1740.2 1764.4 1778.8 66955.0 69147.8 70637.3 71963.0 72541.0 10069.7 10144.8 10295.2 10483.4 10517.8 14040.8 14076.2 14077.4 14100.4 14158.4 7029.7 7307.9 7309.6 7625.0 7670.2 6962.3 6963.0 7198.6 7235.0 7296.4 4283.3 4361.7 4368.9 4507.8 4544.6 1715.7 1760.2 1785.7 1810.8 1820.6 367.3 373.9 765.2 784.3 873.9 9941.4 9986.5 10207.9 10274.0 10762.7 378.1 383.0 383.7 384.3 387.8 5538.9 5584.6 5647.9 5652.6 5696.3 383904.0 388146.0 398633.0 400647.0 404515.0 81108.6 81133.0 81912.0 82934.6 83050.6 899.8 903.1 931.7 944.4 945.7 15670.2 15875.8 16080.2 16193.6 16578.0 37282.3 38855.8 38956.0 39262.6 39498.8 54100.7 55777.4 56294.4 56953.7 57339.4 7616.6 7640.5 7687.2 7708.8 7875.2 10672.2 11187.7 11330.6 12093.5 12341.8 9728.7 9900.5 10276.0 10413.7 10526.3 9979.7 10315.4 10322.4 10368.6 10430.3 9704.6 10424.3 10463.4 10515.9 10859.1 7713.8 7752.8 7768.8 7782.2 7827.5 10469.8 10564.6 10829.6 10949.9 11002.4 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#3] mootools.html Cycles:180 Average:5118.68 Median:4848.29 stddev:2928.07 (60.4%) stddev-sans-first:2922.65 12:25:29 INFO - PROCESS | 5228 | Values: 1358.1 1405.5 1460.8 1625.1 1636.6 6937.6 7155.6 7457.7 8091.6 8099.6 7462.0 7800.2 7821.6 8062.8 8072.6 2155.2 2194.0 2194.6 2195.6 2201.7 2168.3 2203.5 2209.1 2219.0 2221.3 2110.8 2122.5 2132.4 2143.3 2217.2 2179.3 2181.9 2186.2 2189.8 2192.0 2143.6 2170.0 2195.8 2198.7 2212.2 7830.1 7848.1 7930.2 8102.4 8125.9 4474.1 4648.9 4651.4 4710.1 4726.5 2909.1 3022.2 3064.2 3080.7 3097.4 910.8 979.8 982.6 990.9 1008.5 10706.3 10760.9 10780.4 10791.7 10819.5 3996.3 4137.1 4250.7 4274.4 4302.0 9241.2 9244.9 9254.1 9256.5 9262.7 4544.8 4578.5 4581.3 4619.1 4628.0 3676.2 3764.6 4007.6 4161.0 4167.9 3500.9 3587.9 3684.1 3727.2 3745.6 1127.0 1128.7 1134.9 1141.7 1147.2 632.6 639.4 784.7 805.0 824.7 4860.6 5034.2 5101.2 5106.4 5204.4 406.9 415.5 425.3 427.1 427.3 3323.3 3445.0 3478.5 3481.9 3509.1 9094.0 9817.8 9851.3 9853.8 9856.8 9774.3 9775.6 9802.6 9803.0 9816.7 7030.5 7247.8 7300.3 7474.3 7478.6 5027.3 5112.9 5220.1 5293.9 5296.0 9027.8 9060.7 9096.2 9241.5 9242.5 9006.5 9134.4 9273.5 9331.7 9365.4 6717.4 6722.3 6736.8 6769.8 6813.3 7312.8 7359.9 7366.1 7389.9 7390.7 6507.7 6533.2 6644.4 6653.7 6819.7 6338.5 6406.8 6425.3 6441.2 6465.9 5788.2 5884.0 5884.9 5898.9 5902.8 4477.5 4574.6 4578.7 4648.0 4673.5 4533.5 4590.8 4692.9 4729.2 4836.0 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#4] prototype.html Cycles:180 Average:4290.65 Median:4882.99 stddev:1250.61 (25.6%) stddev-sans-first:1217.61 12:25:29 INFO - PROCESS | 5228 | Values: 293.8 297.0 303.6 305.6 305.8 4899.9 4916.3 4921.9 4924.4 4926.9 4832.3 4850.3 4852.6 4854.5 4862.3 3558.6 3674.7 3772.2 3852.9 3887.4 3875.1 3881.0 3881.4 3885.5 3891.5 3857.3 3864.1 3867.4 3869.9 3876.6 3778.8 3802.2 3841.6 3871.8 3881.4 3393.1 3395.2 3409.6 3414.4 3415.9 4758.3 4781.7 4832.9 4836.4 4841.4 4374.4 4393.6 4394.9 4419.0 4422.1 4937.8 5053.7 5117.8 5120.8 5147.4 3670.4 3677.8 3684.9 3688.9 3693.2 5339.8 5345.8 5347.9 5363.1 5371.5 3655.8 3776.2 3783.7 3788.2 3827.9 3852.9 3859.6 3864.0 3869.3 3872.5 4312.5 4361.3 4361.7 4367.7 4371.8 5005.5 5110.9 5132.0 5133.3 5136.6 1306.5 1334.7 1335.7 1337.1 1341.7 818.9 840.9 853.2 864.1 865.8 4316.6 4373.2 4426.5 4521.6 4540.3 5321.6 5332.7 5335.5 5341.5 5344.2 4526.2 4533.3 4538.9 4539.2 4546.5 2692.6 2770.7 2803.5 2810.4 2821.2 5017.6 5018.4 5030.2 5036.0 5043.8 5274.2 5445.0 5445.2 5457.0 5463.3 5071.9 5091.8 5102.4 5106.5 5106.6 5393.0 5393.4 5412.5 5414.8 5428.9 5137.3 5200.6 5225.7 5271.8 5275.9 5048.6 5054.6 5063.2 5063.5 5068.8 3735.2 3755.1 3757.8 3759.5 3761.4 5116.9 5328.7 5334.2 5338.7 5356.4 5206.4 5215.7 5225.8 5227.5 5232.4 5181.8 5182.3 5191.0 5202.9 5205.4 4866.1 5092.6 5100.4 5119.4 5128.3 4982.9 5080.3 5130.5 5212.3 5225.1 5067.2 5087.5 5098.0 5103.8 5106.1 12:25:29 INFO - PROCESS | 5228 | 12:25:29 INFO - PROCESS | 5228 | [#5] yui.html Cycles:180 Average:18042.02 Median:21795.88 stddev:26185.55 (120.1%) stddev-sans-first:26226.30 12:25:29 INFO - PROCESS | 5228 | Values: 612.4 614.5 625.7 658.2 719.1 13131.5 14131.6 14138.3 14143.0 14168.7 13843.7 14445.9 14462.8 14965.3 15085.3 753.8 770.7 771.5 771.8 772.3 728.3 763.5 765.5 767.8 770.4 807.2 814.9 815.7 816.1 816.2 805.3 811.1 811.2 811.7 814.4 590.3 592.2 592.5 592.6 592.9 13884.4 13889.7 13946.6 13950.2 13970.5 12119.0 12165.5 12246.3 12419.3 12834.0 10520.5 10623.2 10801.9 10853.9 11026.6 3269.2 3331.8 3338.0 3358.2 3382.9 64813.0 66163.3 66206.0 67577.0 67832.7 10194.4 10323.2 10452.9 10546.5 10609.2 19028.5 19181.6 19210.9 19260.3 19550.4 6935.0 6948.3 6995.2 7010.0 7068.0 3659.9 3774.1 3855.0 3897.0 3938.4 2911.6 3018.5 3036.8 3060.4 3065.0 454.1 456.1 465.3 471.2 472.3 464.7 485.1 534.7 549.7 560.7 7683.2 7978.3 8082.4 8111.4 8230.8 454.8 459.2 471.4 541.0 550.9 3535.5 3558.4 3704.4 3704.8 3757.8 102839.0 103810.0 103924.0 105418.5 107451.5 87174.0 88431.1 89646.6 89730.6 92661.5 62068.7 73321.6 73743.8 76615.2 81446.6 7670.9 8384.0 8396.9 8473.7 8576.1 39653.4 42311.4 43253.1 45029.5 46212.3 51407.5 52195.7 54240.7 55410.0 63252.0 11519.0 11838.7 12035.4 12064.9 12068.9 11708.8 12072.0 12397.4 13098.4 13233.1 10272.3 10796.2 11662.0 12032.8 12366.6 10543.5 10703.1 10851.4 10993.0 11444.3 10968.2 11299.4 11368.5 11687.6 11943.9 9506.1 9686.9 9835.4 9946.4 10190.0 10205.2 10449.5 10466.9 10638.2 10661.1 12:25:29 INFO - PROCESS | 5228 | -------- Summary: end -------- 12:25:29 INFO - PROCESS | 5228 | 12:25:30 INFO - TEST-INFO | 5228: exit 0 12:25:30 INFO - TEST-OK | dromaeo_css | took 1118038ms 12:25:30 INFO - TEST-START | kraken 12:25:30 INFO - operating with platform_type : linux_ 12:25:30 INFO - Initialising browser for kraken test... 12:25:30 INFO - TEST-INFO | started process 5599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVRPUbW/profile http://localhost:53777/getInfo.html) 12:25:35 INFO - PROCESS | 5599 | __metrics Screen width/height:1600/1200 12:25:35 INFO - PROCESS | 5599 | colorDepth:24 12:25:35 INFO - PROCESS | 5599 | Browser inner width/height: 1024/697 12:25:35 INFO - PROCESS | 5599 | __metrics 12:25:35 ERROR - PROCESS | 5599 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 12:25:36 INFO - TEST-INFO | 5599: exit 0 12:25:36 INFO - Browser initialized. 12:25:36 INFO - Running cycle 1/1 for kraken test... 12:25:36 INFO - TEST-INFO | started process 5719 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVRPUbW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:49 INFO - PROCESS | 5719 | RSS: Main: 186306560 12:25:49 INFO - PROCESS | 5719 | 12:25:52 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53777/tests/kraken/driver.html?testName=audio-beat-detection) 12:25:53 INFO - PROCESS | 5719 | RSS: Main: 199823360 12:25:53 INFO - PROCESS | 5719 | 12:25:55 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53777/tests/kraken/driver.html?testName=audio-dft) 12:25:56 INFO - PROCESS | 5719 | RSS: Main: 197799936 12:25:56 INFO - PROCESS | 5719 | 12:25:59 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53777/tests/kraken/driver.html?testName=audio-fft) 12:26:00 INFO - PROCESS | 5719 | RSS: Main: 193437696 12:26:00 INFO - PROCESS | 5719 | 12:26:02 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53777/tests/kraken/driver.html?testName=audio-oscillator) 12:26:02 INFO - PROCESS | 5719 | RSS: Main: 193880064 12:26:02 INFO - PROCESS | 5719 | 12:26:05 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53777/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:26:05 INFO - PROCESS | 5719 | RSS: Main: 194433024 12:26:05 INFO - PROCESS | 5719 | 12:26:08 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53777/tests/kraken/driver.html?testName=imaging-darkroom) 12:26:09 INFO - PROCESS | 5719 | RSS: Main: 194338816 12:26:09 INFO - PROCESS | 5719 | 12:26:13 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53777/tests/kraken/driver.html?testName=imaging-desaturate) 12:26:13 INFO - PROCESS | 5719 | RSS: Main: 192856064 12:26:13 INFO - PROCESS | 5719 | 12:26:16 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53777/tests/kraken/driver.html?testName=json-parse-financial) 12:26:17 INFO - PROCESS | 5719 | RSS: Main: 193953792 12:26:17 INFO - PROCESS | 5719 | 12:26:19 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53777/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:26:19 INFO - PROCESS | 5719 | RSS: Main: 194125824 12:26:19 INFO - PROCESS | 5719 | 12:26:21 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:26:22 INFO - PROCESS | 5719 | RSS: Main: 194482176 12:26:22 INFO - PROCESS | 5719 | 12:26:24 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:26:25 INFO - PROCESS | 5719 | RSS: Main: 194596864 12:26:25 INFO - PROCESS | 5719 | 12:26:28 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:26:28 INFO - PROCESS | 5719 | RSS: Main: 194273280 12:26:28 INFO - PROCESS | 5719 | 12:26:31 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:26:32 INFO - PROCESS | 5719 | RSS: Main: 194371584 12:26:32 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | Cycle 1(1): loaded http://localhost:53777/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53777/tests/kraken/driver.html?testName=ai-astar) 12:26:34 INFO - PROCESS | 5719 | RSS: Main: 196784128 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | __start_tp_report 12:26:34 INFO - PROCESS | 5719 | _x_x_mozilla_page_load 12:26:34 INFO - PROCESS | 5719 | _x_x_mozilla_page_load_details 12:26:34 INFO - PROCESS | 5719 | |i|pagename|runs| 12:26:34 INFO - PROCESS | 5719 | |0;ai-astar;110;118;118;116;116;118;119;116;116;118 12:26:34 INFO - PROCESS | 5719 | |1;audio-beat-detection;161;129;131;165;128;124;126;128;156;126 12:26:34 INFO - PROCESS | 5719 | |2;audio-dft;177;180;163;166;165;164;164;173;166;165 12:26:34 INFO - PROCESS | 5719 | |3;audio-fft;77;77;77;115;78;77;77;78;100;76 12:26:34 INFO - PROCESS | 5719 | |4;audio-oscillator;84;84;106;85;85;84;99;85;84;116 12:26:34 INFO - PROCESS | 5719 | |5;imaging-gaussian-blur;118;118;118;119;118;118;118;118;117;120 12:26:34 INFO - PROCESS | 5719 | |6;imaging-darkroom;149;149;148;148;150;147;147;147;150;149 12:26:34 INFO - PROCESS | 5719 | |7;imaging-desaturate;96;97;97;96;97;96;97;97;95;96 12:26:34 INFO - PROCESS | 5719 | |8;json-parse-financial;65;66;65;66;65;66;71;65;65;66 12:26:34 INFO - PROCESS | 5719 | |9;json-stringify-tinderbox;66;66;65;66;66;66;66;69;66;68 12:26:34 INFO - PROCESS | 5719 | |10;stanford-crypto-aes;85;86;85;85;90;84;83;84;85;85 12:26:34 INFO - PROCESS | 5719 | |11;stanford-crypto-ccm;138;138;139;137;137;135;138;146;186;138 12:26:34 INFO - PROCESS | 5719 | |12;stanford-crypto-pbkdf2;179;177;173;174;174;179;177;175;175;174 12:26:34 INFO - PROCESS | 5719 | |13;stanford-crypto-sha256-iterative;74;79;71;71;70;69;71;71;70;73 12:26:34 INFO - PROCESS | 5719 | __end_tp_report 12:26:34 INFO - PROCESS | 5719 | __start_cc_report 12:26:34 INFO - PROCESS | 5719 | _x_x_mozilla_cycle_collect,865 12:26:34 INFO - PROCESS | 5719 | __end_cc_report 12:26:34 INFO - PROCESS | 5719 | __startTimestamp1474485994533__endTimestamp 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ------- Summary: start ------- 12:26:34 INFO - PROCESS | 5719 | Number of tests: 14 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:2.55 (2.2%) stddev-sans-first:1.20 12:26:34 INFO - PROCESS | 5719 | Values: 110.0 118.0 118.0 116.0 116.0 118.0 119.0 116.0 116.0 118.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:130.00 stddev:16.30 (12.5%) stddev-sans-first:14.89 12:26:34 INFO - PROCESS | 5719 | Values: 161.0 129.0 131.0 165.0 128.0 124.0 126.0 128.0 156.0 126.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#2] audio-dft Cycles:10 Average:168.30 Median:166.00 stddev:6.07 (3.7%) stddev-sans-first:5.57 12:26:34 INFO - PROCESS | 5719 | Values: 177.0 180.0 163.0 166.0 165.0 164.0 164.0 173.0 166.0 165.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#3] audio-fft Cycles:10 Average:83.20 Median:77.00 stddev:13.30 (17.3%) stddev-sans-first:13.91 12:26:34 INFO - PROCESS | 5719 | Values: 77.0 77.0 77.0 115.0 78.0 77.0 77.0 78.0 100.0 76.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:84.50 stddev:11.63 (13.8%) stddev-sans-first:12.04 12:26:34 INFO - PROCESS | 5719 | Values: 84.0 84.0 106.0 85.0 85.0 84.0 99.0 85.0 84.0 116.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 12:26:34 INFO - PROCESS | 5719 | Values: 118.0 118.0 118.0 119.0 118.0 118.0 118.0 118.0 117.0 120.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:149.00 stddev:1.17 (0.8%) stddev-sans-first:1.22 12:26:34 INFO - PROCESS | 5719 | Values: 149.0 149.0 148.0 148.0 150.0 147.0 147.0 147.0 150.0 149.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:97.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 12:26:34 INFO - PROCESS | 5719 | Values: 96.0 97.0 97.0 96.0 97.0 96.0 97.0 97.0 95.0 96.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:66.00 stddev:1.83 (2.8%) stddev-sans-first:1.90 12:26:34 INFO - PROCESS | 5719 | Values: 65.0 66.0 65.0 66.0 65.0 66.0 71.0 65.0 65.0 66.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:1.17 (1.8%) stddev-sans-first:1.24 12:26:34 INFO - PROCESS | 5719 | Values: 66.0 66.0 65.0 66.0 66.0 66.0 66.0 69.0 66.0 68.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:1.87 (2.2%) stddev-sans-first:1.99 12:26:34 INFO - PROCESS | 5719 | Values: 85.0 86.0 85.0 85.0 90.0 84.0 83.0 84.0 85.0 85.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:138.00 stddev:15.31 (11.1%) stddev-sans-first:16.12 12:26:34 INFO - PROCESS | 5719 | Values: 138.0 138.0 139.0 137.0 137.0 135.0 138.0 146.0 186.0 138.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:176.00 stddev:2.16 (1.2%) stddev-sans-first:1.94 12:26:34 INFO - PROCESS | 5719 | Values: 179.0 177.0 173.0 174.0 174.0 179.0 177.0 175.0 175.0 174.0 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:71.00 stddev:2.88 (4.1%) stddev-sans-first:2.96 12:26:34 INFO - PROCESS | 5719 | Values: 74.0 79.0 71.0 71.0 70.0 69.0 71.0 71.0 70.0 73.0 12:26:34 INFO - PROCESS | 5719 | -------- Summary: end -------- 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ###!!! [Parent][OnMaybeDequeueOne] Error: Channel error: cannot send/recv 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | 12:26:34 INFO - PROCESS | 5719 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x2000C,name=PAPZ::Msg_Destroy) Channel error: cannot send/recv 12:26:34 INFO - PROCESS | 5719 | 12:26:35 INFO - TEST-INFO | 5719: exit 0 12:26:35 INFO - TEST-OK | kraken | took 64906ms 12:26:35 INFO - SUITE-END | took 1182s 12:26:36 INFO - Completed test suite (00:19:43) 12:26:36 INFO - javascript benchmark 12:26:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6283.898045003276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2595.7534034266746, 2605.452854900471, 2614.6339512468244, 2630.934226644334, 2650.9867450662755, 4610.23931051377, 4732.1482133216, 4778.235482322824, 4781.545753153449, 4835.202191638376, 4672.696274742139, 4688.953110468894, 4727.550882666142, 4742.099001189771, 4782.402199725035, 4314.762688052156, 4359.716618419809, 4405.757683327416, 4441.178381999324, 4450.376947227391, 4480.171168333869, 4492.326151077332, 4507.256302710049, 4523.253663145577, 4524.027334123163, 4288.09949910516, 4294.076773493683, 4349.869034051169, 4359.738415695037, 4402.779861006937, 4343.370211319341, 4345.956540434587, 4363.214621368153, 4374.0595771908875, 4415.801288942005, 4331.917020744814, 4352.260115780325, 4355.7822108894425, 4364.018095928423, 4381.38660587518, 4582.427196600425, 4646.581807637295, 4658.58072773452, 4718.433787945469, 4719.292106184065, 5019.498050194988, 5050.520200580939, 5057.811414317633, 5098.209777484496, 5134.383873935151, 5139.177731562932, 5143.305653736773, 5155.664881782672, 5160.767765450564, 5197.506236907526, 4734.26618874075, 4774.4509381421585, 4777.761111944389, 4791.065742180242, 4792.041591681677, 5222.216667499905, 5246.921296180561, 5261.500157485033, 5276.551493123051, 5326.9467305327225, 4841.370621819211, 5018.523240292239, 5024.020316038337, 5060.5951523878, 5072.442031376546, 4995.575376093, 5095.694258344512, 5128.948710512922, 5163.948360516422, 5180.4560521145495, 5039.97480012605, 5071.619628527875, 5077.238414237894, 5080.263361812516, 5136.332276803992, 5007.924881126786, 5023.2214006829, 5032.924506132411, 5033.370828646419, 5063.49365063498, 4221.1346673932385, 4266.679999000087, 4316.6546676265825, 4323.048929235563, 4327.156204540147, 3757.492738480325, 3796.468494410785, 3796.6393192646656, 3825.3305671507374, 3862.980685096613, 4405.9559404405545, 4438.842342008315, 4453.599176074169, 4484.887877803016, 4504.549545045535, 5007.974960125249, 5052.646314757932, 5066.847994560169, 5073.847784566469, 5186.81846135391, 4346.413234213341, 4375.431193944767, 4416.712913660666, 4419.955800441954, 4428.557144285545, 4559.0, 4567.748773817406, 4575.382323386367, 4575.565321294472, 4645.024544845553, 4732.6687131900435, 4761.2382018876815, 4777.283407488904, 4805.0, 4812.807487700593, 5105.0, 5136.8202112925865, 5143.665661731976, 5175.482451754794, 5190.143626301618, 4766.046790641954, 4819.59033482158, 4820.71075735457, 4821.975890120527, 4862.562369386772, 4902.92645610309, 4965.577925876341, 4985.451600324034, 5066.316047333563, 5077.289179514797, 5149.356330458692, 5181.119209734279, 5183.377994640668, 5185.818496352609, 5221.791128354823, 5009.323741295024, 5014.648974571745, 5019.924701129413, 5021.1213037717525, 5083.644144909821, 4821.059893320909, 4894.314795928644, 4900.901981960412, 4922.803087876445, 5007.148784706682, 4911.7298548580175, 4931.753412329297, 4974.179260422135, 4980.8754781130765, 4991.5258050485145, 5041.470645582308, 5044.4451110377095, 5045.571126454292, 5056.494350565061, 5092.6435149539175, 4771.904561908812, 4913.459519452786, 4923.5814955729165, 4929.014197160654, 4980.701157930535], "value": 4683.031459084036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.1740243920701, 544.2054600283586, 546.6091744402751, 549.4176173256349, 556.449115375778, 36175.0, 37713.62286377136, 37918.62081379185, 41935.79032104843, 41961.37057944126, 38289.617103828954, 38712.61287387125, 38739.80630096853, 38755.61244387555, 38837.61162388375, 7538.811529711774, 7672.424568157383, 7674.386049116058, 7676.270754278363, 7678.42411819113, 7602.885956710654, 7700.653470593837, 7705.576193309365, 7713.228677132298, 7715.151333353355, 7690.846183076335, 7692.076950765916, 7721.266479684449, 7738.651760670783, 7738.883916741253, 7628.923710762877, 7659.731909383145, 7693.461457697963, 7713.537187768696, 7715.537067775951, 7642.885356719654, 7688.0, 7697.615119244071, 7702.229777022309, 7703.0, 37364.81317593422, 37625.24749505022, 37685.623143768484, 37702.62297377019, 37760.24479510422, 2767.4603452326805, 2807.761340286078, 2902.7097290271017, 3098.907032788997, 3569.8929032129076, 27482.862585686944, 27927.85587170819, 28026.579601305995, 28043.3728434808, 29048.564271535943, 3524.7004004659634, 3526.0832183632183, 3539.2213712983103, 3691.9815400923094, 3714.182879766447, 242277.0, 243159.0, 243544.0, 243778.0, 244127.0, 48335.516644833806, 48903.51096489061, 48920.90646856186, 49048.0, 49059.50940490621, 31410.0, 31906.36187276288, 31972.0, 34143.48784768231, 34147.317053658786, 14270.500532481314, 14548.636284092983, 14595.635109122357, 14641.853581464262, 14649.780253296209, 10649.201309901788, 10830.241883068109, 10831.891681083245, 10854.511546980404, 10865.348079115278, 17002.91929936831, 17227.913860430617, 17228.0, 17275.222614982355, 17276.740848887275, 6786.423154031864, 6902.827429314207, 6916.0, 7021.929780702128, 7098.893516597255, 735.5733674468795, 875.159846547309, 905.9048799875918, 960.7742180587557, 965.9712406287282, 25918.0, 26904.461910761675, 27155.321116971838, 27389.86305068462, 27481.725182748316, 855.2516548020482, 866.8526350520426, 918.8300164469595, 989.8663680403198, 1000.2098342309639, 11502.769944601061, 13495.392707328194, 13707.451701931812, 14029.883324073364, 14279.64300892486, 383457.0, 387655.0, 387693.0, 388829.0, 389544.0, 45542.31686524705, 45895.770521147184, 46116.0, 46168.3074753879, 46193.30710039352, 1119.6696974392573, 1157.5659127827064, 1175.6531823112032, 1183.7099910521977, 1184.7926612842891, 25006.37484062913, 25341.366465838502, 25642.230733077293, 25980.220593381473, 26035.609465858408, 38216.80891595524, 38965.610343896195, 39102.24039918267, 39111.60888391193, 39119.0, 338414.0, 340378.0, 341113.0, 342102.0, 342482.0, 23629.40926476783, 23957.52084958326, 23965.400864978514, 23971.400714981566, 24181.274561763174, 32062.198445038128, 32695.70920364898, 32763.0, 33091.50362744513, 33182.336353272316, 32253.21322039154, 32923.835380822944, 33095.33809323848, 33351.0, 33642.663573363956, 33376.165595859326, 33390.0, 33847.66152338445, 33851.492227616116, 34324.828375857964, 32531.674683252866, 33024.0, 33181.0, 36843.63156368509, 37214.0, 33952.490712638835, 36774.63225367819, 36921.0, 36979.63020369762, 37164.44253336148, 29291.707082928897, 29557.408851823275, 29917.850410748677, 30485.390292194475, 30599.2350191247], "value": 16800.36524439187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.2666287436503, 366.5656197406074, 370.69788122680023, 382.11159055196674, 384.17977617786016, 15057.322420491077, 15444.227788610566, 15601.375944962216, 15757.0, 15763.290651920663, 16030.679386412265, 16785.83214167858, 16911.661766764657, 17070.14649267538, 17089.743653845202, 4832.71003739775, 5063.392392912856, 5106.417010726686, 5120.667156634807, 5121.641485096045, 5098.439171691111, 5161.4838516148275, 5162.457941916082, 5163.251328557357, 5169.819056333029, 5227.921581176266, 5512.972435137819, 5553.333599968009, 5672.631278966875, 5687.431256874321, 5361.892762144736, 5521.558275337963, 5591.2731344925105, 5637.661740295575, 5639.8590035249035, 5238.842834714965, 5280.313559237313, 5293.470652934714, 5296.25852380667, 5303.257543943851, 10976.780464390667, 10999.780004399867, 11025.61410350634, 11056.944715276371, 11219.0, 1585.7700633408092, 1669.5575672446812, 1671.523615769512, 1703.2165204006171, 1751.2469638055632, 8982.281417486585, 9070.410423322463, 9101.772455688595, 9160.954195229047, 9610.711678649583, 1659.444086231114, 1725.0857045765765, 1740.2342969093559, 1764.373647355196, 1778.7776527934009, 66955.0, 69147.78748675283, 70637.29362706358, 71963.0, 72541.0, 10069.69790906274, 10144.847827282596, 10295.176385889112, 10483.423411712276, 10517.789644207072, 14040.789388159186, 14076.155430674193, 14077.436902523785, 14100.365483553265, 14158.362873670709, 7029.683664235122, 7307.926920730831, 7309.64771517271, 7625.046869141357, 7670.194629563815, 6962.303769622998, 6963.0, 7198.5680859148615, 7234.963825180946, 7296.379807716404, 4283.2932566126865, 4361.716488428242, 4368.934465983013, 4507.752073635981, 4544.568266014723, 1715.7340612205128, 1760.1551255397226, 1785.7232129020022, 1810.8189181081787, 1820.5812663087563, 367.3067160390597, 373.92895349883435, 765.1659690936906, 784.3058892879842, 873.886394768676, 9941.403515789076, 9986.500674966368, 10207.897921020694, 10274.0, 10762.677119686421, 378.128414005714, 382.96744776693737, 383.7122158381214, 384.30249097887247, 387.75571390024106, 5538.86152846174, 5584.5811564132855, 5647.915281270784, 5652.63257888236, 5696.287964004499, 383904.0, 388146.0, 398633.0, 400647.0, 404515.0, 81108.59445702734, 81133.0, 81912.0, 82934.58532707297, 83050.58474707708, 899.8155378147464, 903.1420150856712, 931.7421481000595, 944.4191822029402, 945.6595625574788, 15670.216489175724, 15875.761863572287, 16080.195990200209, 16193.59516012109, 16578.0, 37282.254354912206, 38855.80572097121, 38956.02609934774, 39262.60737392589, 39498.80250598844, 54100.729496353844, 55777.442225578845, 56294.43705562892, 56953.71523142524, 57339.42660573341, 7616.619169041636, 7640.541567505968, 7687.192844751221, 7708.8072798178255, 7875.212478752079, 10672.199585030998, 11187.664370068911, 11330.640323161273, 12093.455794489093, 12341.753164936472, 9728.659496917573, 9900.50497475109, 10276.0, 10413.739656508345, 10526.263161578616, 9979.650712225037, 10315.432651203955, 10322.380657160595, 10368.637097701545, 10430.322029068086, 9704.563294651898, 10424.322419042737, 10463.372197668165, 10515.894841051491, 10859.131269498579, 7713.845723085619, 7752.8449431012195, 7768.7669369918995, 7782.210091679059, 7827.45207835446, 10469.842952355568, 10564.630237941636, 10829.566817327219, 10949.890501095206, 11002.394868282321], "value": 7636.1856479941125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.0561509750723, 1405.4799724102081, 1460.7954886315918, 1625.06558728731, 1636.5908522869274, 6937.583744975293, 7155.570665760058, 7457.701691932323, 8091.595420228987, 8099.595020248986, 7462.029936108298, 7800.1809809969845, 7821.6480258388365, 8062.798430039237, 8072.55600941948, 2155.234891613482, 2193.967090493636, 2194.561087782442, 2195.648696208599, 2201.7137772089604, 2168.30614203455, 2203.4711669199355, 2209.0942713487475, 2219.04581030157, 2221.2669818959703, 2110.8311335093154, 2122.490602255455, 2132.3709505695856, 2143.2927134045726, 2217.246136313653, 2179.259051922338, 2181.879996600186, 2186.180182431588, 2189.7591264960843, 2192.0464597899963, 2143.6248656485054, 2170.0, 2195.83531235158, 2198.7471440784357, 2212.2478357358577, 7830.099538553025, 7848.058233012018, 7930.167332430127, 8102.39232057598, 8125.878111828328, 4474.082813023322, 4648.86053418398, 4651.372064771246, 4710.1286262041285, 4726.527347265281, 2909.11272062021, 3022.2293315204483, 3064.1573567268956, 3080.7227349538425, 3097.365040166783, 910.7996240827008, 979.8481235408523, 982.6413359123868, 990.9256805739599, 1008.4755926917961, 10706.340517219922, 10760.892391076068, 10780.40707761073, 10791.730206744816, 10819.513121909533, 3996.2806694794635, 4137.110521237888, 4250.702450828412, 4274.380214868826, 4301.956980430155, 9241.168294853496, 9244.907550924405, 9254.12085851843, 9256.490893000815, 9262.675806346746, 4544.772761361919, 4578.542145785462, 4581.312803079498, 4619.076184763061, 4627.953720462752, 3676.227992121631, 3764.5858955514873, 4007.6393124618926, 4161.0, 4167.895802604899, 3500.8949731508096, 3587.9282414351565, 3684.060564556021, 3727.1800203955086, 3745.606711295329, 1126.9887301127048, 1128.729105014807, 1134.9432528373547, 1141.6632093532428, 1147.2141583015662, 632.585656395062, 639.405353021685, 784.7292684024003, 805.017878188609, 824.7257986411009, 4860.586850117709, 5034.219695947134, 5101.158308878993, 5106.361704786902, 5204.42751297357, 406.9165821006746, 415.47442485256175, 425.28976609062937, 427.1265262538117, 427.3056283539249, 3323.321722530155, 3445.0139910358416, 3478.4608385700258, 3481.860725570949, 3509.0876372143425, 9094.0, 9817.803643927082, 9851.261155413373, 9853.75365615851, 9856.802863942825, 9774.266929980138, 9775.560099795526, 9802.607895684092, 9803.01969803014, 9816.656417025513, 7030.543014704028, 7247.782566523013, 7300.269973002763, 7474.2809153894905, 7478.588677622783, 5027.271045698426, 5112.897742045064, 5220.086484865209, 5293.920591191212, 5296.0, 9027.819443611223, 9060.682876099305, 9096.226820720312, 9241.491717955578, 9242.537873106183, 9006.459612423276, 9134.36059475857, 9273.489958052372, 9331.72004839856, 9365.39124956903, 6717.429018533479, 6722.294159113223, 6736.831579210363, 6769.796906092825, 6813.250542440229, 7312.780616581511, 7359.889601655872, 7366.079240094989, 7389.926100738924, 7390.704371825067, 6507.707153178213, 6533.18335208099, 6644.435223005905, 6653.733850646114, 6819.659017049028, 6338.461230795434, 6406.807795766134, 6425.261094974137, 6441.194850643669, 6465.935340646533, 5788.247527821356, 5884.0, 5884.911726324194, 5898.882022359443, 5902.822915312548, 4477.462704475483, 4574.565416285448, 4578.702384345007, 4647.976760116313, 4673.462551806586, 4533.523979982055, 4590.77046147698, 4692.906141877212, 4729.243321068612, 4836.0], "value": 4037.6410225479412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.8369205091174, 296.99109026729207, 303.57651076747936, 305.5676218151316, 305.8256793627633, 4899.853004409864, 4916.262560615909, 4921.852344429664, 4924.384451943507, 4926.871436637738, 4832.323474713543, 4850.2724591311235, 4852.636052296074, 4854.466008739036, 4862.32249873793, 3558.572971243448, 3674.65090816372, 3772.226693527834, 3852.9422058669143, 3887.378019516878, 3875.1087249932534, 3880.9611903881023, 3881.359575670025, 3885.514310711161, 3891.455196272524, 3857.32496813058, 3864.111254411487, 3867.439221312908, 3869.8645547405845, 3876.554196267423, 3778.773273603565, 3802.16352402471, 3841.615838416136, 3871.845126194963, 3881.4177873319086, 3393.117789374756, 3395.151212196951, 3409.573803274591, 3414.3854106260865, 3415.9487607685905, 4758.262469317261, 4781.665283430161, 4832.903341933177, 4836.395450568679, 4841.419029716421, 4374.365716971021, 4393.5606439356125, 4394.8901277468, 4419.0, 4422.059330545184, 4937.7777999990085, 5053.722045287508, 5117.750228604826, 5120.820771272987, 5147.382314122329, 3670.3576874046935, 3677.7793332400142, 3684.9263014739554, 3688.870889518854, 3693.2059607184583, 5339.786408543692, 5345.786168553253, 5347.919781203285, 5363.115086010804, 5371.5165635092635, 3655.79190567239, 3776.244751049801, 3783.659470647655, 3788.2234142000825, 3827.885163445101, 3852.9019229519454, 3859.614038596174, 3863.96136038636, 3869.342211823997, 3872.477215575861, 4312.525622181557, 4361.323994780814, 4361.738295702268, 4367.650587952956, 4371.759553224603, 5005.4744251853035, 5110.897782044412, 5132.0, 5133.25567792668, 5136.5890728741615, 1306.4578200046874, 1334.726381091874, 1335.6660834791303, 1337.1413734908485, 1341.7249463859885, 818.8617821228497, 840.9159084091542, 853.1809462915546, 864.05817658751, 865.7705707987388, 4316.568343165721, 4373.190959672471, 4426.535213802569, 4521.59305662492, 4540.318952157203, 5321.600879934021, 5332.68003919766, 5335.519803217651, 5341.4925582069645, 5344.198370244494, 4526.207913615104, 4533.342665313577, 4538.863834084983, 4539.205639013159, 4546.499885012646, 2692.636494073314, 2770.6813716422466, 2803.4953708332696, 2810.353618667718, 2821.238265668258, 5017.573506251936, 5018.419197952633, 5030.195168772979, 5036.0, 5043.798248070109, 5274.182501712241, 5445.047116754552, 5445.1832225166545, 5457.0, 5463.262459567987, 5071.873203169876, 5091.821786237464, 5102.413222479388, 5106.4893510648635, 5106.591472682177, 5393.0, 5393.381985404441, 5412.485813847678, 5414.810481633124, 5428.91856622143, 5137.33214682089, 5200.609954253522, 5225.738713064407, 5271.761136133083, 5275.920861187008, 5048.596112310933, 5054.59563234933, 5063.1645778446145, 5063.468335824686, 5068.797248110034, 3735.234276973268, 3755.136318646781, 3757.7829080842434, 3759.5112635357227, 3761.39817629178, 5116.850922612437, 5328.72110693423, 5334.199870019528, 5338.652987555796, 5356.3572371315695, 5206.375234971828, 5215.739213039409, 5225.817096401608, 5227.503387178212, 5232.3982741985155, 5181.766820493011, 5182.32629758129, 5190.987757387274, 5202.947970520247, 5205.3753549572775, 4866.124097662313, 5092.618053646065, 5100.38795344561, 5119.387392971202, 5128.333316668809, 4982.900341993068, 5080.288759573737, 5130.461301563282, 5212.348456442945, 5225.137852254487, 5067.189249720027, 5087.465816089258, 5097.974510127426, 5103.795848166032, 5106.085218043234], "value": 3889.9215198669817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [612.3570251236201, 614.4715544631616, 625.7177933547187, 658.2232965101184, 719.086759815035, 13131.474741010361, 14131.646708832284, 14138.293085345744, 14143.0, 14168.716625667481, 13843.653908652288, 14445.92777036116, 14462.783058254108, 14965.326560304786, 15085.321160547774, 753.8040109571496, 770.6840195519839, 771.4946709905021, 771.8147644565292, 772.2856357868977, 728.3299364584595, 763.4617594595796, 765.4603504529322, 767.7696690992708, 770.3759954436924, 807.1726480357651, 814.8899898513685, 815.6615004773012, 816.0859836982559, 816.2042009041197, 805.279275048831, 811.0834756724922, 811.1766556944735, 811.7483580090161, 814.3892080939296, 590.3004939147124, 592.1620906417414, 592.4638202426811, 592.5763079398208, 592.943670351316, 13884.4446222151, 13889.722205555832, 13946.58160255194, 13950.23273719945, 13970.51103211393, 12119.0, 12165.452554635063, 12246.326452045134, 12419.31693756843, 12834.037447191404, 10520.528225685506, 10623.150147988143, 10801.891981080167, 10853.891461085366, 11026.558937642485, 3269.1663625775277, 3331.816750078745, 3338.0, 3358.227607650254, 3382.8646854125805, 64813.0, 66163.3383666162, 66206.0, 67577.0, 67832.660836696, 10194.388336699822, 10323.174146068297, 10452.947735261427, 10546.525406356732, 10609.15126789855, 19028.52428689266, 19181.616367672847, 19210.903945480182, 19260.32588859411, 19550.413487595393, 6935.0, 6948.305169483011, 6995.1955525114245, 7009.9649501752165, 7067.964660176666, 3659.8902032939054, 3774.1130834253927, 3854.9614503855164, 3897.0, 3938.350172221609, 2911.6214892064318, 3018.54721791733, 3036.7722420818527, 3060.403221371811, 3064.969350306513, 454.0963482669464, 456.0992040719619, 465.3438651501367, 471.24130150457734, 472.33400904724175, 464.6816930402685, 485.09771824406573, 534.7326336831584, 549.6811849127497, 560.7280468972546, 7683.195633593809, 7978.321842643324, 8082.393820463489, 8111.3916456266015, 8230.753077407688, 454.7544326063955, 459.16890428324837, 471.4460508902026, 540.9540189083893, 550.9201165830932, 3535.505029295901, 3558.448440491728, 3704.444333350019, 3704.7962362070343, 3757.7745335279424, 102839.0, 103810.0, 103924.0, 105418.4729076365, 107451.46274268735, 87174.0, 88431.11568884228, 89646.55176724205, 89730.55134724284, 92661.53669231746, 62068.689656552335, 73321.6333918327, 73743.78202724659, 76615.23384766192, 81446.59276703579, 7670.8849367260655, 8384.0, 8396.916030839613, 8473.66105355779, 8576.142385761374, 39653.40519892261, 42311.36532951947, 43253.05097805234, 45029.54970450253, 46212.30681539847, 51407.48592514027, 52195.73902130465, 54240.72879635577, 55410.0, 63252.0, 11519.0, 11838.704032398915, 12035.398230088636, 12064.939675301568, 12068.879311206776, 11708.824367634663, 12072.0, 12397.380130993233, 13098.410571524113, 13233.07368484197, 10272.280940334404, 10796.244262901522, 11662.0, 12032.819507707216, 12366.567170149001, 10543.525541350504, 10703.143748499946, 10851.40317282557, 10993.0, 11444.256123351955, 10968.23222374426, 11299.43502824872, 11368.488418021043, 11687.649370518899, 11943.940280298542, 9506.144446999802, 9686.9031309686, 9835.409875407213, 9946.35348702332, 10190.0, 10205.183585313009, 10449.477526123816, 10466.947665261625, 10638.202134839763, 10661.09380702649], "value": 6524.352446045424, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 118.0, 116.0, 116.0, 118.0, 119.0, 116.0, 116.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 129.0, 131.0, 165.0, 128.0, 124.0, 126.0, 128.0, 156.0, 126.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 180.0, 163.0, 166.0, 165.0, 164.0, 164.0, 173.0, 166.0, 165.0], "value": 168.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 115.0, 78.0, 77.0, 77.0, 78.0, 100.0, 76.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 106.0, 85.0, 85.0, 84.0, 99.0, 85.0, 84.0, 116.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 119.0, 118.0, 118.0, 118.0, 118.0, 117.0, 120.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 148.0, 150.0, 147.0, 147.0, 147.0, 150.0, 149.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0, 95.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 71.0, 65.0, 65.0, 66.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 69.0, 66.0, 68.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 90.0, 84.0, 83.0, 84.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 139.0, 137.0, 137.0, 135.0, 138.0, 146.0, 186.0, 138.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 173.0, 174.0, 174.0, 179.0, 177.0, 175.0, 175.0, 174.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 71.0, 71.0, 70.0, 69.0, 71.0, 71.0, 70.0, 73.0], "value": 71.9, "unit": "score"}]}]} 12:26:36 INFO - Return code: 0 12:26:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:26:36 ERROR - # TBPL SUCCESS # 12:26:36 INFO - Running post-action listener: _resource_record_post_action 12:26:36 INFO - [mozharness: 2016-09-21 19:26:36.343104Z] Finished run-tests step (success) 12:26:36 INFO - Running post-run listener: _resource_record_post_run 12:26:36 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408588288; Read time: 8; Write time: 278452 12:26:36 INFO - TinderboxPrint: CPU usage
13.4% 12:26:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:26:36 INFO - TinderboxPrint: I/O write bytes / time
408,588,288 / 278,452 12:26:36 INFO - TinderboxPrint: CPU idle
8,290.2 (86.6%) 12:26:36 INFO - TinderboxPrint: CPU user
1,218.4 (12.7%) 12:26:36 INFO - TinderboxPrint: Swap in / out
0 / 0 12:26:36 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115765248; Read time: 0; Write time: 74432 12:26:36 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 292823040; Read time: 0; Write time: 204020 12:26:36 INFO - Running post-run listener: _upload_blobber_files 12:26:36 INFO - Blob upload gear active. 12:26:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:26:36 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 12:26:36 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:26:36 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:26:37 INFO - (blobuploader) - INFO - Open directory for files ... 12:26:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:26:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:26:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:38 INFO - (blobuploader) - INFO - Done attempting. 12:26:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:26:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:26:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:38 INFO - (blobuploader) - INFO - Done attempting. 12:26:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:26:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:43 INFO - (blobuploader) - INFO - Done attempting. 12:26:43 INFO - (blobuploader) - INFO - Iteration through files over. 12:26:43 INFO - Return code: 0 12:26:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:26:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:26:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32769b51a3ab1d4c794bb528f0a1d5055d3765ef959e33126e8e376cf5cdebd7d14960fc8bcddb87d41e4003590c968720b99f5d8ca4d0117c58ca5cbd3ab815", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3d4a1ab0295842debf7c14c5d35227220583a057f071be3a66025d7f8ac187b5d86d6e2d0778bdfcaeb9933c3acf4c8cc9977be92d98ba1126ebeba83c12371c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6d37aad70555aea25a7dab8a0ae106a7b86865a97d4d97bd75bcee3e5d1fe18a86660c985141a5c0279251b1e5ce4d19582be06f0d40f3d03a8153b76f627612"} 12:26:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:26:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:26:43 INFO - Contents: 12:26:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32769b51a3ab1d4c794bb528f0a1d5055d3765ef959e33126e8e376cf5cdebd7d14960fc8bcddb87d41e4003590c968720b99f5d8ca4d0117c58ca5cbd3ab815", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3d4a1ab0295842debf7c14c5d35227220583a057f071be3a66025d7f8ac187b5d86d6e2d0778bdfcaeb9933c3acf4c8cc9977be92d98ba1126ebeba83c12371c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6d37aad70555aea25a7dab8a0ae106a7b86865a97d4d97bd75bcee3e5d1fe18a86660c985141a5c0279251b1e5ce4d19582be06f0d40f3d03a8153b76f627612"} 12:26:43 INFO - Running post-run listener: copy_logs_to_upload_dir 12:26:43 INFO - Copying logs to upload dir... 12:26:43 INFO - mkdir: /builds/slave/test/build/upload/logs 12:26:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.405639 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-21 12:26:42.476412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 12:26:42.480115) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 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/Autoland-Non-PGO/sha512/32769b51a3ab1d4c794bb528f0a1d5055d3765ef959e33126e8e376cf5cdebd7d14960fc8bcddb87d41e4003590c968720b99f5d8ca4d0117c58ca5cbd3ab815", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3d4a1ab0295842debf7c14c5d35227220583a057f071be3a66025d7f8ac187b5d86d6e2d0778bdfcaeb9933c3acf4c8cc9977be92d98ba1126ebeba83c12371c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6d37aad70555aea25a7dab8a0ae106a7b86865a97d4d97bd75bcee3e5d1fe18a86660c985141a5c0279251b1e5ce4d19582be06f0d40f3d03a8153b76f627612"} build_url:https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/Bp8Bktc5Qk6LeEulVWSQfg/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/Autoland-Non-PGO/sha512/32769b51a3ab1d4c794bb528f0a1d5055d3765ef959e33126e8e376cf5cdebd7d14960fc8bcddb87d41e4003590c968720b99f5d8ca4d0117c58ca5cbd3ab815", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3d4a1ab0295842debf7c14c5d35227220583a057f071be3a66025d7f8ac187b5d86d6e2d0778bdfcaeb9933c3acf4c8cc9977be92d98ba1126ebeba83c12371c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6d37aad70555aea25a7dab8a0ae106a7b86865a97d4d97bd75bcee3e5d1fe18a86660c985141a5c0279251b1e5ce4d19582be06f0d40f3d03a8153b76f627612"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 12:26:42.505128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:26:42.505448) ========= 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=6d15e502d8e29d5d2676d664000001c3-1474481262.538812-568953189 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004384 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 12:26:42.590641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 12:26:42.590961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 12:26:42.591306) ========= ========= Total master_lag: 0.17 =========